From 4b695f3d9e742afc1cc7fecd818f2d691512349a Mon Sep 17 00:00:00 2001 From: klein panic Date: Fri, 13 Dec 2024 22:35:46 -0500 Subject: [PATCH] adding shit --- src/MidasV1/logs/MidasV1.log | 115 +++ .../initial_checks.cpython-311.pyc | Bin 23773 -> 18994 bytes src/MidasV1/tests/test_stock_retriever.log | 866 +++++++++--------- 3 files changed, 546 insertions(+), 435 deletions(-) diff --git a/src/MidasV1/logs/MidasV1.log b/src/MidasV1/logs/MidasV1.log index dc90254..39794ba 100644 --- a/src/MidasV1/logs/MidasV1.log +++ b/src/MidasV1/logs/MidasV1.log @@ -162,3 +162,118 @@ Traceback (most recent call last): ^^^^^^^ NameError: name 'MAGENTA' is not defined 2024-12-13 02:13:49,362 - MidasV1.Main - ERROR - Interrupt received. Shutting down gracefully... +2024-12-13 13:45:40,800 - MidasV1.Main - INFO - Starting MidasV1 Trading Bot... +2024-12-13 13:45:40,800 - MidasV1.InitialChecks - INFO - Checking Operating System... +2024-12-13 13:45:40,800 - MidasV1.InitialChecks - INFO - Operating System Linux is supported. +2024-12-13 13:45:40,800 - MidasV1.InitialChecks - INFO - Checking Dependencies... +2024-12-13 13:45:40,800 - MidasV1.InitialChecks - INFO - All dependencies are satisfied. +2024-12-13 13:45:40,800 - MidasV1.InitialChecks - INFO - Checking System Resources... +2024-12-13 13:45:40,801 - MidasV1.InitialChecks - INFO - +CPU Cores: 12 +CPU Clock Speed: 2689.84 MHz +CPU Load Average (1m, 5m, 15m): (0.08935546875, 0.1201171875, 0.09375) +CPU Threads: 12 +Total RAM: 15.31 GB +Used RAM: 2.11 GB (21.4%) +Available RAM: 12.04 GB +Total Swap: 0.95 GB +Used Swap: 0.00 GB (0.0%) +2024-12-13 13:45:40,801 - MidasV1.InitialChecks - INFO - Checking Connectivity with IB Gateway... +2024-12-13 13:45:40,801 - MidasV1.InitialChecks - INFO - ──────────────────────────────────────────────────── +2024-12-13 13:45:40,801 - MidasV1.InitialChecks - INFO - IB Gateway Connection Test +2024-12-13 13:45:40,801 - MidasV1.InitialChecks - INFO - ──────────────────────────────────────────────────── +2024-12-13 13:45:40,801 - MidasV1.InitialChecks - INFO - Attempting to connect to IB Gateway at 127.0.0.1:4002... +2024-12-13 13:45:40,801 - MidasV1.InitialChecks - ERROR - [ERROR] id=-1, code=502, msg=Couldn't connect to TWS. Confirm that "Enable ActiveX and Socket EClients" +is enabled and connection port is the same as "Socket Port" on the +TWS "Edit->Global Configuration...->API->Settings" menu. Live Trading ports: +TWS: 7496; IB Gateway: 4001. Simulated Trading ports for new installations +of version 954.1 or newer: TWS: 7497; IB Gateway: 4002 +2024-12-13 13:45:40,801 - MidasV1.InitialChecks - ERROR - [ERROR] Connection to IB Gateway was closed unexpectedly! +2024-12-13 13:45:42,801 - MidasV1.Main - ERROR - Interrupt received. Shutting down gracefully... +2024-12-13 13:45:42,801 - MidasV1.Main - ERROR - Initial checks failed. Exiting program. +2024-12-13 13:45:50,765 - MidasV1.Main - INFO - Starting MidasV1 Trading Bot... +2024-12-13 13:45:50,766 - MidasV1.InitialChecks - INFO - Checking Operating System... +2024-12-13 13:45:50,766 - MidasV1.InitialChecks - INFO - Operating System Linux is supported. +2024-12-13 13:45:50,766 - MidasV1.InitialChecks - INFO - Checking Dependencies... +2024-12-13 13:45:50,766 - MidasV1.InitialChecks - INFO - All dependencies are satisfied. +2024-12-13 13:45:50,766 - MidasV1.InitialChecks - INFO - Checking System Resources... +2024-12-13 13:45:50,766 - MidasV1.InitialChecks - INFO - +CPU Cores: 12 +CPU Clock Speed: 2154.09 MHz +CPU Load Average (1m, 5m, 15m): (0.0751953125, 0.115234375, 0.091796875) +CPU Threads: 12 +Total RAM: 15.31 GB +Used RAM: 2.10 GB (21.4%) +Available RAM: 12.04 GB +Total Swap: 0.95 GB +Used Swap: 0.00 GB (0.0%) +2024-12-13 13:45:50,766 - MidasV1.InitialChecks - INFO - Checking Connectivity with IB Gateway... +2024-12-13 13:45:50,766 - MidasV1.InitialChecks - INFO - ──────────────────────────────────────────────────── +2024-12-13 13:45:50,766 - MidasV1.InitialChecks - INFO - IB Gateway Connection Test +2024-12-13 13:45:50,767 - MidasV1.InitialChecks - INFO - ──────────────────────────────────────────────────── +2024-12-13 13:45:50,767 - MidasV1.InitialChecks - INFO - Attempting to connect to IB Gateway at 127.0.0.1:4002... +2024-12-13 13:45:50,767 - MidasV1.InitialChecks - ERROR - [ERROR] id=-1, code=502, msg=Couldn't connect to TWS. Confirm that "Enable ActiveX and Socket EClients" +is enabled and connection port is the same as "Socket Port" on the +TWS "Edit->Global Configuration...->API->Settings" menu. Live Trading ports: +TWS: 7496; IB Gateway: 4001. Simulated Trading ports for new installations +of version 954.1 or newer: TWS: 7497; IB Gateway: 4002 +2024-12-13 13:45:50,767 - MidasV1.InitialChecks - ERROR - [ERROR] Connection to IB Gateway was closed unexpectedly! +2024-12-13 13:45:55,776 - MidasV1.InitialChecks - ERROR - [ERROR] Connection not established within timeout. +2024-12-13 13:45:55,777 - MidasV1.InitialChecks - WARNING - [WARN] No connection. Check Gateway settings and try again. +2024-12-13 13:45:55,777 - MidasV1.Main - ERROR - Initial checks failed. Exiting program. +2024-12-13 13:50:18,585 - MidasV1.Main - INFO - Starting MidasV1 Trading Bot... +2024-12-13 13:50:18,585 - MidasV1.InitialChecks - INFO - Checking Operating System... +2024-12-13 13:50:18,585 - MidasV1.InitialChecks - INFO - Operating System Linux is supported. +2024-12-13 13:50:18,585 - MidasV1.InitialChecks - INFO - Checking Dependencies... +2024-12-13 13:50:18,585 - MidasV1.InitialChecks - INFO - All dependencies are satisfied. +2024-12-13 13:50:18,585 - MidasV1.InitialChecks - INFO - Checking System Resources... +2024-12-13 13:50:18,586 - MidasV1.InitialChecks - INFO - +CPU Cores: 12 +CPU Clock Speed: 1581.67 MHz +CPU Load Average (1m, 5m, 15m): (0.76904296875, 0.32080078125, 0.16259765625) +CPU Threads: 12 +Total RAM: 15.31 GB +Used RAM: 2.74 GB (25.8%) +Available RAM: 11.37 GB +Total Swap: 0.95 GB +Used Swap: 0.00 GB (0.0%) +2024-12-13 13:50:18,586 - MidasV1.InitialChecks - INFO - Checking Connectivity with IB Gateway... +2024-12-13 13:50:18,586 - MidasV1.InitialChecks - INFO - ──────────────────────────────────────────────────── +2024-12-13 13:50:18,586 - MidasV1.InitialChecks - INFO - IB Gateway Connection Test +2024-12-13 13:50:18,586 - MidasV1.InitialChecks - INFO - ──────────────────────────────────────────────────── +2024-12-13 13:50:18,586 - MidasV1.InitialChecks - INFO - Attempting to connect to IB Gateway at 127.0.0.1:4002... +2024-12-13 13:50:18,642 - MidasV1.InitialChecks - INFO - [INFO] Managed accounts: DUE064818 +2024-12-13 13:50:18,684 - MidasV1.InitialChecks - INFO - [INFO] Next valid order ID: 1 +2024-12-13 13:50:18,684 - MidasV1.InitialChecks - INFO - [INFO/STATUS] id=-1, code=2104, msg=Market data farm connection is OK:usfarm +2024-12-13 13:50:18,684 - MidasV1.InitialChecks - INFO - [INFO/STATUS] id=-1, code=2106, msg=HMDS data farm connection is OK:ushmds +2024-12-13 13:50:18,684 - MidasV1.InitialChecks - INFO - [INFO/STATUS] id=-1, code=2158, msg=Sec-def data farm connection is OK:secdefnj +2024-12-13 13:50:18,728 - MidasV1.InitialChecks - INFO - [INFO] Connected successfully! +2024-12-13 13:50:18,728 - MidasV1.InitialChecks - INFO - Requesting account summary... +2024-12-13 13:50:18,771 - MidasV1.InitialChecks - INFO - [ACCOUNT SUMMARY] ReqId:1, Account:DUE064818, BuyingPower = 4014612.28 USD +2024-12-13 13:50:18,772 - MidasV1.InitialChecks - INFO - [ACCOUNT SUMMARY] ReqId:1, Account:DUE064818, EquityWithLoanValue = 1003653.07 USD +2024-12-13 13:50:18,772 - MidasV1.InitialChecks - INFO - [ACCOUNT SUMMARY] ReqId:1, Account:DUE064818, NetLiquidation = 1004890.64 USD +2024-12-13 13:50:18,772 - MidasV1.InitialChecks - INFO - [ACCOUNT SUMMARY] ReqId:1, Account:DUE064818, TotalCashValue = 1003653.07 USD +2024-12-13 13:50:18,772 - MidasV1.InitialChecks - INFO - [ACCOUNT SUMMARY END] ReqId: 1 +2024-12-13 13:50:23,729 - MidasV1.InitialChecks - INFO - [INFO] Successfully retrieved account summary data. +2024-12-13 13:50:23,729 - MidasV1.InitialChecks - INFO - IB Gateway is connected and ready for upcoming modules. +2024-12-13 13:50:23,729 - MidasV1.InitialChecks - INFO - ──────────────────────────────────────────────────── +2024-12-13 13:50:23,729 - MidasV1.InitialChecks - INFO - Test Complete +2024-12-13 13:50:23,729 - MidasV1.InitialChecks - INFO - ──────────────────────────────────────────────────── +2024-12-13 13:50:23,729 - MidasV1.InitialChecks - INFO - All initial checks passed successfully. +2024-12-13 13:50:23,730 - MidasV1.StockListPetitioner - INFO - Starting Module 2: IBJTS List Petitioner... +2024-12-13 13:50:23,730 - MidasV1.StockListPetitioner - INFO - Loaded Scanner Criteria: + - Search Volume: 1000000 + - Net Change: 0.5 + - Percent Change: 2.0 +2024-12-13 13:50:23,730 - MidasV1.StockListPetitioner - INFO - Initiating scanner subscription with the above criteria... +2024-12-13 13:50:23,730 - MidasV1.StockListPetitioner - INFO - Scanner subscription requested successfully on attempt 1. +2024-12-13 13:50:23,730 - MidasV1.StockListPetitioner - INFO - Waiting for scanner data (timeout in 15 seconds)... +2024-12-13 13:50:24,556 - MidasV1.InitialChecks - ERROR - [ERROR] id=1001, code=162, msg=Historical Market Data Service error message:Scanner type with code ALL is disabled +2024-12-13 13:50:38,730 - MidasV1.StockListPetitioner - ERROR - Scanner subscription timed out. +2024-12-13 13:50:38,731 - MidasV1.StockListPetitioner - ERROR - Scanner subscription canceled due to timeout. +2024-12-13 13:50:38,731 - MidasV1.Main - INFO - Refined Stock List: [] +2024-12-13 13:50:38,731 - MidasV1.Main - INFO - Initial checks and Module 2 completed. Please review the logs and ensure everything is correct. +2024-12-13 13:50:38,732 - MidasV1.InitialChecks - ERROR - [ERROR] id=1001, code=365, msg=No scanner subscription found for ticker id:1001 +2024-12-13 13:50:41,816 - MidasV1.Main - INFO - User chose to exit the program. +2024-12-13 13:50:41,816 - MidasV1.InitialChecks - ERROR - [ERROR] Connection to IB Gateway was closed unexpectedly! +2024-12-13 13:50:41,816 - MidasV1.Main - INFO - Disconnected from IB Gateway. diff --git a/src/MidasV1/modules/__pycache__/initial_checks.cpython-311.pyc b/src/MidasV1/modules/__pycache__/initial_checks.cpython-311.pyc index 83934b8d8f44b7fe0450f8a6878cbfccaa1fabd6..6da8b9dc868e8321dfb50606ca61f9309c706376 100644 GIT binary patch delta 5642 zcmcb+lX24&M!w~|yj%8%SRY`&{N)=E;T*%9HuR1UXVTf*CY9t12c3bLUTf&8?px z7n)aGT2PQ*RFaya;9rnhlvt9Pm#z?8SzMBuYo$fpb@!PCQiokQXhhr|r|iyWF)I5aQ7P>~1&14A;%x1fmr&cVmD4HSzO5-Ch6 z%;`+EjOh%uOf`%y46)|5%$y7*tdk9GWh8opl^IIdKyd}ZDhx#uj0`nQlO6fRCi8{~ ziA6H>n3OYAFh??!GuAK_C8V&Vur6SoTo|Sz0!|Msp!5LI%fPUJZ89SlC@nNv%1%DP zFKU;K~XHc~yS= z`0;>S2xOkb4H?f3A=@K2MeZoSXzG5&)ct~t=LH$h$$63zktm5Z;v!Gv6`sfoJdtRL z)f#^aE#_xnU|7a)#t(w0VM>6WL8)jWME+6U|?VXssFr;fq`K< zLk2?%<6_2I)*7Z7)-r}7)yWerl_wV{DNjDCBse)rM2wMja-otETM9!kgC^T#cV&A; z?r7JbApf9Ph0GLNO=gJmKnV_iS%ux&s_iX_M2{Qz1dXK3PCR(@PB$-pmTN3cnZ? zG?|NRK(>Hlu?Q3npdbJVxPk~@5a9levpXz!2S2N4M%B5`t#zBC&+Ev8KF)pui@G5LyqFgTLI!7IX82Tx)%rFpnq7-Ge1nQE98 zFoO#D$rDTkIeH`@AwT)GfrcYW;sU#g56Mlgc`2IANX|+Hc?OhD!F4FeJ?WEkE%Ybv z(HG}*%}bdNk`9@C(@+k$h|E#}O;k|I!HdW*TZ zq^KwuWL6l6hy)SQAOe)Cic&zVbWo<}g_ae`sX00E@hcgN>_E1Gg4zLGX58WqNi8l3 zF9OvCMMdTe3=H`o1?LzU7=AP`O#Wk}QP0Yo#Q1>$L4IUr;1lnO{J;bj;$z?w2NllR zD~vxdF!O372)H_sC>K~66K@IQ3g-_DAbN)~ggyY}UkC{P0G7BAp9En*$a)9`Hw~os z0|(eRMqV$*6~&&v*o8KlY=tQJNhsr6-qtBnV%iD&%C%)klp zEF*9HWNniSYi3>}kPn!7jX*wy5O6b4L=p1f3{sRo`JBlO%VbF2vINBtD0ZPK(kU5zkA`3)-Of1R;vGPFCh!oFU zpm=u9$xO{FDJo=OU|2f2!^{z!1Byx}pEsK*UsRH7Qfy(|%ry*IER*A{T$ni-Y9{~V zl#pgX$b;m-3K>7qFUND1!fgy!`4F_tkrfc>&+#gP|&XCGs$3 z3=CQPlNg79l)_WXhSJa#0+oS`Y?BY#3Ns3Wyr5MAN(*3T zFfiZ?wG`gTfkI|1DSWjY;P$PDoghmJTP^40i?$LfHJmB@DFVxw85mZ>OI*P ziBDE`nmT#ElfvdFPOgkW;*g{cs_k!a2D=70204cK2Nl7y!!7pY{Jgx>7X-8~2xu3BD)K|@ zYEF6#hxDwRwV4lVF*<8A`)RVH7l%2Z@&}Y*Af*?mRiVj*R4RemHPG_tmLMW_Kt-iv zK|xU&0|UcKP?&-0Q&4sI%gd|Yl2_*g1113}>OzO1zcRh6An1N6oCx8#hh4BfaGFMQ0WDUR0RizBB9CGye;_?L2h&a zRoOop7(Vb#mh;KdGT}7(z<^22)%XY!{Q@E&@;Q??`DnX2GIHvCU?7%Uz`Y{sBUtqp zFbUP^F=X2qQE0MJup`@N zP%u2*Tp9d`QRxDQ{sj(waHJ%I>;^joI_3l7f0mov6B@-RGx>R_1!K)*2Y&I%v0=iK zJ;FpM&*u~nsbxuF0vE}k6av-@>F-YlbA` z%rUl;ePTRvZ!lP1Kt&H2R4$;R4=fD4*-Qz9!3>&QiNUN43|tc*I!^q+zFCR!C8I%NQK~{_UU5lcPEKlyLQy}k!R{$CO<}HKoXwEJGMBlQY4SmF zrO5|4g?vjmK@NrB6xJH1*$gRcbD5VhGBB)WgorXQFfyd@1vAtzrm$y2jEaC7#W9z; zmbr!@3#M@Ldu@ryv0Q>CD5i4OFk?3rMHScNZ=C8n+}La;V4Dq!ImFq<0}cw_xy&G2 zC9&G7$zQc_av4{?hJr_8UP?}Cu|jG}da6QlVsUD*LRx;2LTO%cX+c4LQAuiwf`4#o z@#H6529ry#U7rSmy%yzTqUKDS*(y;T2z#pSCUhStX&VR z!!WnXLOR;gIM=PTq_iki0ctwfE~ptG6OvLv=_ENnuQ)R$wJ0@34`j&jQM-kuTIoz^71x1;8CAU~pi;D7#ZZQ{E7T;n?t;j6VWGUijU|_h#o?jea zQdy9Ci@&%uIXShsI6gPExHvIA^%gsnQOv@?z@VU@pim?*nVm9jLbdg8z3Xk3e9zC#B@I{`GD?A|=ctY;-@b`F32<-Ik@$LY_2c%g5HvEE+ zE~@#V8`v)hg z1H)>VBttDDntmBRxyiS*1$c_xQ&_;cf^{zQBt5gq9XdQ2DQw^pfgQ6%C=mr&1;JU| z5SoD@g#$hRmPo?n7(gWmS^;UwP|IAyynuIdppeL90WrS%T9y6*@9ZDFPtM zB}EV%j1&Y~iqPZ(kQh)(5w2mM&5(k{uoSLf22GKwg_F1N*+mjjh3IjCawZ6S=B4Eq zW)>HN%9fPWg4Dc})V$=()Z)pX_*CkvnDq3j1kfv` zD)}I!YDyt3GbdFcFTX?~Ex$A`MIo_7;TNM`kpu$+Ly;JW040Q5j75GZX`GdTfk7CQ z#&AVzZgHuda)YD$qVJhMHo<%*#_+z#s%Os z#4pA_SzJPVvOF(;VhR(;N{A5?nR+6F88n%HF)CLv+1eI?>M>2`Tdc(;MVSS+xQa^> zi%N>iGfOgVu@)EPWR|RCy2YetaErMhJG~gB7F47$Ffjb$vdPITE=kVMEwHOHnan6I zGC4wkr(Vw{CqFqcr`S#pA)N{;`$8EQ7#bKZ$dp6S4(Aia=POTDo~%Ao4H8LCyPjNl zDY@`MQR&6xvMb4D7i7w31kaD06*)6{PV{wIy-TusD@rcPnp}}J*${X|)*Pe>Y#2&u z0kQ>BRDgpLlX~5@Qx8`#!%7BC##_wUsg*^#AU7g=kJF|&73w=< znD0`MdB!9m2jK>y6?hIkv^z45`s6M;tLX!vlG)( zi;F>poPt6_kpM`Q0RsaAfjCMBc|(VRf#F93!v*Oy2wLELfcZG*AitKop0)Wl+VD{pcjzmzJdomZNdmzkGt z1*){GM8FCQG7DgBK?U6+g~@gNiuFaH;#E_ih!5lwP(a^e$}hgfQjl1Zaf>A@KQr$Z zdrD?eUSe+QEspqjP}Yl&zr~hXky%_)TqFn5Bnu+sL4*P*xLEQFQuA(cf|{8*nR%(j zw|EP()8mU$i}Op1l2eOsahK;8WoPE4#}}uTXmZv=YJ5TDEFTZHoEO|WfYu-TrrI|&kDYsy{Qs5DDO8{E5z(tCbKrS-}g+HhP3M!RA*Z~}Mw}d7e2$=ANfMhk9 z7(lV}fopQ1fO!2~8M!&`^L=LdtQA@#zFvNn{9cKRnod_VoesEM@QS%86MID__5z3G zQ+~mo^8UK6x(jmp7x@jY@Ed$!VBwUwAro}L&i{f;5CnDjA~zUvFY@SI;nBIkqXTXg z7|)2kAY=?d7kNys@R(fSF{wwXwV(0|UJz1V5PXqW^9rx#2L?t?ofQ!`#O1GxYhDu9 zyeO`HMO?eX;{!7zrw&*WOnd|>`vM}sT%5#@A3reV>N#~jurbJ1FkX-;KVWe|ru?Ez z`4yS+3mlR+z*%ss>=i?=3$or9WxcP+dSBp>x&cenproyBb3r=oqIB97>9h+R5)Zhg zKQOR?17$W0JhfCOpih&ERXiq;kfBoPP$B@wAScS|KTX02R#U1S$ z6yzTS7O4`Djm-fD)WL=<^fL#eIYaf%8<&uD3WzWB zHD=z#_*9E(C37{nMfnZSj0Q3@!yV3qmgbyC4>vp^z&li8(J%LVcSXSOf`H!*4gCo$R|HgVsA+*{vQqK+GtBA zH%h1Sm9T?72qse4C-0D!VM*bb%RSjq0+c%5NNWmPfNEZ51zUw*j0#n%0StggrDzH~6K|EV7E2|Cm>`G>|c!Nb2$e-|r<>alh@odXM{&AcvC8weU z_K(kw)C*!h5Oh(%_lkh;1sEz4n(QVghe)TyxG;vruSyb{(0mi~64ODYTXJ%KX3hP}!OT%Jt6ByXt#YC>s30~l0> zfJ+liMsRK@1{n?-hEP+8W!ngHncn111sQR$%MvdNBwZ0mx&T8(LX+1jY_SLjwUfAu zlN0muQj1&?OA<9X!DbbKBCDtjloZQBL0Jj(_>Pkq#m#t- zjM+AsQ7Kp&RGMQ?U`t>L%nH@puwn+aufxbt!vOERa)S3dh%B#)c|l5VwNLdPxB$U)-^9hlNrh1^`O85MGtsb2jt;KkS|?8 zzMMQwnU8HRNI~A@Rmv(-;Lr^@ka|HZ1cELKgkBK{y&w=e`JA!|2PgGCFd$?-go2v} z())o6Y#bA>ALEMR4-6n0nS`qaOK?Ed1~P7N2B`{UL?)5c1~PttsZ9o}NoM@W%)klp zEE8|?d}9h$7^{*|n&1a;w@6lUhg_5DbbCP!vMb$}RSC zXuyMG1(IH%9WlYl6V;s*N*M zE}AG+E~H6^Wsshep=R=XUI}R=Q)(EpKv@i2qt!5Eu}|J8Bxbtt491nSTFbmYs0K2G$Aq&(z0JBrL)^MYa2G+9Fuq@yQyGlSzfUiUmtPV_M z2}~~F6jx+T;i+Y-VaNid0L5nDSi>= zN}=cyn4F+1u8(5w22&Jww59Oj2wq`6{#thAxs-f8@ySp0gaU{Rb>vo=0C>z0OA@JJ zSRjH3IM5ga*pmzlS=^wAVo4FKFa-l4a}Ch0MKC7=;AI4?!A#01J=U|^WdFqZ|> zq{$x)v>Bx)%NPdhgXXPFa=|TyDrs1s%^BKB&d*awOU%qkO|epdjOt2GUSc?v9Xxeq zoIBalNP!hRYPC7i$d%Dc8d9)=8u+(3gIxn0gB(NrgTPI+B6taWiyhhnFY*Vqy*OPf zl2Z#n{dI7gpa?R0HTi?FrU+<=^A>AyPHJkwE%x%nBG4GxWK9z@D^OqJ7Q3&byQ^P_ z<1H2^e;=1ytU<29t|6NI;PU>K5Y&#${5<%u6=Q0V%j5jDaw1QaePI$jiTx+36oLBI*zP%^tHV17lw{DOcvNFyJr#>tya&E&w! zY%VAoUl6l_po;>wR|IS?2-tpLVVnHMRHa_w0|Sd75^+Pt^?>3971s+Yt`mH3h)7KF zzAmDANknx)?nM!kDEnT|H{t-B30|k9xz40!CK^j4lWm-4zg>D}F)DVX4XmF^3Cc4tqr* z^hE*3D*}!e1RPP#x&Tf_#h{st!-8tg77T|ixLk#pk1#p1x$-g}n$JdA)@=7igsA1SlP6PQG9fVB*QhtN4KdI|*qE zZUY&|fym-TT$BARrJcdHKs!y~CKWuVgEM#$NZT#u#DW4Ohj7B%S_%#hMFNx8T3Ygh zn)}5Lj0_Avel#$A5S#qfGE2*v)93>OCNWpzBS`cMh=9mfP42YPb_-MvjtsxxEqFDo-fp~;5Ul1v5QH5Hp%tR#tpNnxpFtzpPwnhcs4ws8^CLotfA zh85H_#W0GYh9#ImlPxi6bC`<}BO7EqbaJn278fFrCVzL;^a9rrnjA$cprG>v5um1C z(IOBFJREov#M%WSK$Qn{VCWWiadu`wd~!x=a&~bMIBl()oZu!V2%1Mg9UN+RGh_<_ zh2qi4``jd%Svk!o-*PjMx{GsN!DKFXr+RQ$V;y|H!7qP-LmnLLewvJKnw;oa4V=Lc z-T^xu?1{S|Pk`!Uux9YoMiHoM0Vd2q`E3e#3ax>mVe&e6`Fb%{i4P2L!a$zY_yYr+ zaAIUIG5f%f&BQ8FrQ z_#$bLv%z&Q4~S(6BKCm@(CVWi(8O$!C%vKd? 0 -2024-12-13 02:01:32,318 - DEBUG - 139881771299920 connState: None -> 0 -2024-12-13 02:01:32,319 - DEBUG - Connecting to 127.0.0.1:4002 w/ id:1 -2024-12-13 02:01:32,319 - DEBUG - 139881771299920 connState: 0 -> 1 -2024-12-13 02:01:32,319 - DEBUG - msg b'\x00\x00\x00\tv100..193' -2024-12-13 02:01:32,319 - DEBUG - REQUEST b'API\x00\x00\x00\x00\tv100..193' -2024-12-13 02:01:32,319 - DEBUG - acquiring lock -2024-12-13 02:01:32,319 - DEBUG - acquired lock -2024-12-13 02:01:32,319 - DEBUG - releasing lock -2024-12-13 02:01:32,319 - DEBUG - release lock -2024-12-13 02:01:32,319 - DEBUG - sendMsg: sent: 17 -2024-12-13 02:01:32,320 - DEBUG - no fields -2024-12-13 02:01:32,321 - DEBUG - len 30 raw:b'\x00\x00\x00\x1a187\x0020241213 02:01:31 EST\x00'| -2024-12-13 02:01:32,322 - DEBUG - ANSWER b'\x00\x00\x00\x1a187\x0020241213 02:01:31 EST\x00' -2024-12-13 02:01:32,322 - DEBUG - read_msg: size: 26 -2024-12-13 02:01:32,322 - DEBUG - size:26 msg:b'187\x0020241213 02:01:31 EST\x00' rest:b''| -2024-12-13 02:01:32,322 - DEBUG - fields (b'187', b'20241213 02:01:31 EST') -2024-12-13 02:01:32,322 - DEBUG - ANSWER Version:187 time:b'20241213 02:01:31 EST' -2024-12-13 02:01:32,322 - DEBUG - 139881771299920 connState: 1 -> 2 -2024-12-13 02:01:32,322 - DEBUG - EReader thread started -2024-12-13 02:01:32,322 - INFO - sent startApi -2024-12-13 02:01:32,322 - INFO - REQUEST startApi {} -2024-12-13 02:01:32,322 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,322 - INFO - SENDING startApi b'\x00\x00\x00\x0871\x002\x001\x00\x00' -2024-12-13 02:01:32,322 - DEBUG - acquiring lock -2024-12-13 02:01:32,322 - DEBUG - acquired lock -2024-12-13 02:01:32,322 - DEBUG - releasing lock -2024-12-13 02:01:32,322 - DEBUG - release lock -2024-12-13 02:01:32,322 - DEBUG - sendMsg: sent: 12 -2024-12-13 02:01:32,322 - INFO - ANSWER connectAck {} -2024-12-13 02:01:32,322 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,323 - DEBUG - len 19 raw:b'\x00\x00\x00\x0f15\x001\x00DUE064818\x00'| -2024-12-13 02:01:32,323 - DEBUG - reader loop, recvd size 19 -2024-12-13 02:01:32,323 - DEBUG - read_msg: size: 15 -2024-12-13 02:01:32,323 - DEBUG - size:15 msg.size:15 msg:|b''| buf:|| -2024-12-13 02:01:32,323 - DEBUG - fields (b'15', b'1', b'DUE064818') -2024-12-13 02:01:32,323 - DEBUG - In interpret(), handleInfo: wrap: meth:None prms:OrderedDict([('self', ), ('accountsList', )]) -2024-12-13 02:01:32,323 - DEBUG - field b'DUE064818' -2024-12-13 02:01:32,324 - DEBUG - arg DUE064818 type -2024-12-13 02:01:32,324 - DEBUG - calling > with <__main__.TestStockRetriever object at 0x7f38c349f450> ['DUE064818'] -2024-12-13 02:01:32,324 - INFO - ANSWER managedAccounts {'accountsList': 'DUE064818'} -2024-12-13 02:01:32,324 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,324 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:32,324 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,366 - DEBUG - len 227 raw:b'\x00\x00\x00\x069\x001\x001\x00\x00\x00\x0064\x002\x00-1\x002104\x00Market data farm connection is OK:usfarm\x00\x00\x00\x00\x00^4\x002\x00-1\x002107\x00HMDS data farm connection is inactive but should be available upon demand.ushmds\x00\x00\x00\x00\x0094\x002\x00-1\x002158\x00Sec-def data farm connection is OK:secdefnj\x00\x00'| -2024-12-13 02:01:32,366 - DEBUG - reader loop, recvd size 227 -2024-12-13 02:01:32,366 - DEBUG - read_msg: size: 6 -2024-12-13 02:01:32,366 - DEBUG - size:6 msg.size:6 msg:|b'\x00\x00\x0064\x002\x00-1\x002104\x00Market data farm connection is OK:usfarm\x00\x00\x00\x00\x00^4\x002\x00-1\x002107\x00HMDS data farm connection is inactive but should be available upon demand.ushmds\x00\x00\x00\x00\x0094\x002\x00-1\x002158\x00Sec-def data farm connection is OK:secdefnj\x00\x00'| buf:|| -2024-12-13 02:01:32,366 - DEBUG - read_msg: size: 54 -2024-12-13 02:01:32,366 - DEBUG - size:54 msg.size:54 msg:|b'\x00\x00\x00^4\x002\x00-1\x002107\x00HMDS data farm connection is inactive but should be available upon demand.ushmds\x00\x00\x00\x00\x0094\x002\x00-1\x002158\x00Sec-def data farm connection is OK:secdefnj\x00\x00'| buf:|| -2024-12-13 02:01:32,366 - DEBUG - read_msg: size: 94 -2024-12-13 02:01:32,366 - DEBUG - size:94 msg.size:94 msg:|b'\x00\x00\x0094\x002\x00-1\x002158\x00Sec-def data farm connection is OK:secdefnj\x00\x00'| buf:|| -2024-12-13 02:01:32,366 - DEBUG - read_msg: size: 57 -2024-12-13 02:01:32,367 - DEBUG - size:57 msg.size:57 msg:|b''| buf:|| -2024-12-13 02:01:32,367 - DEBUG - fields (b'9', b'1', b'1') -2024-12-13 02:01:32,367 - DEBUG - In interpret(), handleInfo: wrap: meth:None prms:OrderedDict([('self', ), ('orderId', )]) -2024-12-13 02:01:32,367 - DEBUG - field b'1' -2024-12-13 02:01:32,367 - DEBUG - arg 1 type -2024-12-13 02:01:32,367 - DEBUG - calling > with <__main__.TestStockRetriever object at 0x7f38c349f450> [1] -2024-12-13 02:01:32,367 - INFO - NextValidId received: 1 -2024-12-13 02:01:32,367 - INFO - Requesting market data for symbols... -2024-12-13 02:01:32,367 - INFO - REQUEST reqMktData {'reqId': 1001, 'contract': 139881771406224: 0,AAPL,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} -2024-12-13 02:01:32,367 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,367 - INFO - SENDING reqMktData b'\x00\x00\x0011\x0011\x001001\x000\x00AAPL\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' -2024-12-13 02:01:32,367 - DEBUG - acquiring lock -2024-12-13 02:01:32,367 - DEBUG - acquired lock -2024-12-13 02:01:32,367 - DEBUG - releasing lock -2024-12-13 02:01:32,367 - DEBUG - release lock -2024-12-13 02:01:32,367 - DEBUG - sendMsg: sent: 53 -2024-12-13 02:01:32,367 - DEBUG - Requested market data for AAPL with ReqId: 1001 -2024-12-13 02:01:32,367 - INFO - REQUEST reqMktData {'reqId': 1002, 'contract': 139881771409296: 0,MSFT,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} -2024-12-13 02:01:32,367 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,367 - INFO - SENDING reqMktData b'\x00\x00\x0011\x0011\x001002\x000\x00MSFT\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' -2024-12-13 02:01:32,367 - DEBUG - acquiring lock -2024-12-13 02:01:32,367 - DEBUG - acquired lock -2024-12-13 02:01:32,367 - DEBUG - releasing lock -2024-12-13 02:01:32,367 - DEBUG - release lock -2024-12-13 02:01:32,367 - DEBUG - sendMsg: sent: 53 -2024-12-13 02:01:32,368 - DEBUG - Requested market data for MSFT with ReqId: 1002 -2024-12-13 02:01:32,368 - INFO - REQUEST reqMktData {'reqId': 1003, 'contract': 139881771409424: 0,GOOGL,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} -2024-12-13 02:01:32,368 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,368 - INFO - SENDING reqMktData b'\x00\x00\x0021\x0011\x001003\x000\x00GOOGL\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' -2024-12-13 02:01:32,368 - DEBUG - acquiring lock -2024-12-13 02:01:32,368 - DEBUG - acquired lock -2024-12-13 02:01:32,368 - DEBUG - releasing lock -2024-12-13 02:01:32,368 - DEBUG - release lock -2024-12-13 02:01:32,368 - DEBUG - sendMsg: sent: 54 -2024-12-13 02:01:32,368 - DEBUG - Requested market data for GOOGL with ReqId: 1003 -2024-12-13 02:01:32,368 - INFO - REQUEST reqMktData {'reqId': 1004, 'contract': 139881771409360: 0,AMZN,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} -2024-12-13 02:01:32,368 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,368 - INFO - SENDING reqMktData b'\x00\x00\x0011\x0011\x001004\x000\x00AMZN\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' -2024-12-13 02:01:32,368 - DEBUG - acquiring lock -2024-12-13 02:01:32,368 - DEBUG - acquired lock -2024-12-13 02:01:32,368 - DEBUG - releasing lock -2024-12-13 02:01:32,368 - DEBUG - release lock -2024-12-13 02:01:32,368 - DEBUG - sendMsg: sent: 53 -2024-12-13 02:01:32,368 - DEBUG - Requested market data for AMZN with ReqId: 1004 -2024-12-13 02:01:32,368 - INFO - REQUEST reqMktData {'reqId': 1005, 'contract': 139881771409552: 0,TSLA,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} -2024-12-13 02:01:32,368 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,368 - INFO - SENDING reqMktData b'\x00\x00\x0011\x0011\x001005\x000\x00TSLA\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' -2024-12-13 02:01:32,368 - DEBUG - acquiring lock -2024-12-13 02:01:32,368 - DEBUG - acquired lock -2024-12-13 02:01:32,368 - DEBUG - releasing lock -2024-12-13 02:01:32,368 - DEBUG - release lock -2024-12-13 02:01:32,368 - DEBUG - sendMsg: sent: 53 -2024-12-13 02:01:32,368 - DEBUG - Requested market data for TSLA with ReqId: 1005 -2024-12-13 02:01:32,368 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,368 - DEBUG - conn:1 queue.sz:3 -2024-12-13 02:01:32,368 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,368 - DEBUG - fields (b'4', b'2', b'-1', b'2104', b'Market data farm connection is OK:usfarm', b'') -2024-12-13 02:01:32,368 - DEBUG - decode b'2' -2024-12-13 02:01:32,368 - DEBUG - decode b'-1' -2024-12-13 02:01:32,368 - DEBUG - decode b'2104' -2024-12-13 02:01:32,368 - DEBUG - decode b'Market data farm connection is OK:usfarm' -2024-12-13 02:01:32,369 - DEBUG - decode b'' -2024-12-13 02:01:32,369 - ERROR - Error. ReqId: -1, Code: 2104, Msg: Market data farm connection is OK:usfarm, Advanced Order Reject JSON: -2024-12-13 02:01:32,369 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,369 - DEBUG - conn:1 queue.sz:2 -2024-12-13 02:01:32,369 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,369 - DEBUG - fields (b'4', b'2', b'-1', b'2107', b'HMDS data farm connection is inactive but should be available upon demand.ushmds', b'') -2024-12-13 02:01:32,369 - DEBUG - decode b'2' -2024-12-13 02:01:32,369 - DEBUG - decode b'-1' -2024-12-13 02:01:32,369 - DEBUG - decode b'2107' -2024-12-13 02:01:32,369 - DEBUG - decode b'HMDS data farm connection is inactive but should be available upon demand.ushmds' -2024-12-13 02:01:32,369 - DEBUG - decode b'' -2024-12-13 02:01:32,369 - ERROR - Error. ReqId: -1, Code: 2107, Msg: HMDS data farm connection is inactive but should be available upon demand.ushmds, Advanced Order Reject JSON: -2024-12-13 02:01:32,369 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,369 - DEBUG - conn:1 queue.sz:1 -2024-12-13 02:01:32,369 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,369 - DEBUG - fields (b'4', b'2', b'-1', b'2158', b'Sec-def data farm connection is OK:secdefnj', b'') -2024-12-13 02:01:32,369 - DEBUG - decode b'2' -2024-12-13 02:01:32,369 - DEBUG - decode b'-1' -2024-12-13 02:01:32,369 - DEBUG - decode b'2158' -2024-12-13 02:01:32,369 - DEBUG - decode b'Sec-def data farm connection is OK:secdefnj' -2024-12-13 02:01:32,369 - DEBUG - decode b'' -2024-12-13 02:01:32,369 - ERROR - Error. ReqId: -1, Code: 2158, Msg: Sec-def data farm connection is OK:secdefnj, Advanced Order Reject JSON: -2024-12-13 02:01:32,369 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,369 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:32,369 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,433 - DEBUG - len 203 raw:b"\x00\x00\x00\xc74\x002\x001001\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AAPL NASDAQ.NMS/TOP/ALL\x00\x00"| -2024-12-13 02:01:32,433 - DEBUG - reader loop, recvd size 203 -2024-12-13 02:01:32,433 - DEBUG - read_msg: size: 199 -2024-12-13 02:01:32,433 - DEBUG - size:199 msg.size:199 msg:|b''| buf:|| -2024-12-13 02:01:32,434 - DEBUG - fields (b'4', b'2', b'1001', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AAPL NASDAQ.NMS/TOP/ALL", b'') -2024-12-13 02:01:32,434 - DEBUG - decode b'2' -2024-12-13 02:01:32,434 - DEBUG - decode b'1001' -2024-12-13 02:01:32,434 - DEBUG - decode b'10089' -2024-12-13 02:01:32,434 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AAPL NASDAQ.NMS/TOP/ALL" -2024-12-13 02:01:32,434 - DEBUG - decode b'' -2024-12-13 02:01:32,434 - ERROR - Error. ReqId: 1001, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AAPL NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: -2024-12-13 02:01:32,434 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,434 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:32,434 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,634 - DEBUG - queue.get: empty -2024-12-13 02:01:32,634 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,634 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:32,634 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,685 - DEBUG - len 203 raw:b"\x00\x00\x00\xc74\x002\x001002\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.MSFT NASDAQ.NMS/TOP/ALL\x00\x00"| -2024-12-13 02:01:32,685 - DEBUG - reader loop, recvd size 203 -2024-12-13 02:01:32,685 - DEBUG - read_msg: size: 199 -2024-12-13 02:01:32,685 - DEBUG - size:199 msg.size:199 msg:|b''| buf:|| -2024-12-13 02:01:32,685 - DEBUG - fields (b'4', b'2', b'1002', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.MSFT NASDAQ.NMS/TOP/ALL", b'') -2024-12-13 02:01:32,685 - DEBUG - decode b'2' -2024-12-13 02:01:32,685 - DEBUG - decode b'1002' -2024-12-13 02:01:32,685 - DEBUG - decode b'10089' -2024-12-13 02:01:32,685 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.MSFT NASDAQ.NMS/TOP/ALL" -2024-12-13 02:01:32,685 - DEBUG - decode b'' -2024-12-13 02:01:32,685 - ERROR - Error. ReqId: 1002, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.MSFT NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: -2024-12-13 02:01:32,685 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,685 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:32,685 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,710 - DEBUG - len 204 raw:b"\x00\x00\x00\xc84\x002\x001003\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.GOOGL NASDAQ.NMS/TOP/ALL\x00\x00"| -2024-12-13 02:01:32,710 - DEBUG - reader loop, recvd size 204 -2024-12-13 02:01:32,710 - DEBUG - read_msg: size: 200 -2024-12-13 02:01:32,710 - DEBUG - size:200 msg.size:200 msg:|b''| buf:|| -2024-12-13 02:01:32,710 - DEBUG - fields (b'4', b'2', b'1003', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.GOOGL NASDAQ.NMS/TOP/ALL", b'') -2024-12-13 02:01:32,710 - DEBUG - decode b'2' -2024-12-13 02:01:32,710 - DEBUG - decode b'1003' -2024-12-13 02:01:32,710 - DEBUG - decode b'10089' -2024-12-13 02:01:32,710 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.GOOGL NASDAQ.NMS/TOP/ALL" -2024-12-13 02:01:32,710 - DEBUG - decode b'' -2024-12-13 02:01:32,710 - ERROR - Error. ReqId: 1003, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.GOOGL NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: -2024-12-13 02:01:32,710 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,710 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:32,710 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,711 - DEBUG - len 203 raw:b"\x00\x00\x00\xc74\x002\x001004\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AMZN NASDAQ.NMS/TOP/ALL\x00\x00"| -2024-12-13 02:01:32,711 - DEBUG - reader loop, recvd size 203 -2024-12-13 02:01:32,711 - DEBUG - read_msg: size: 199 -2024-12-13 02:01:32,711 - DEBUG - size:199 msg.size:199 msg:|b''| buf:|| -2024-12-13 02:01:32,711 - DEBUG - fields (b'4', b'2', b'1004', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AMZN NASDAQ.NMS/TOP/ALL", b'') -2024-12-13 02:01:32,712 - DEBUG - decode b'2' -2024-12-13 02:01:32,712 - DEBUG - decode b'1004' -2024-12-13 02:01:32,712 - DEBUG - decode b'10089' -2024-12-13 02:01:32,712 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AMZN NASDAQ.NMS/TOP/ALL" -2024-12-13 02:01:32,712 - DEBUG - decode b'' -2024-12-13 02:01:32,712 - ERROR - Error. ReqId: 1004, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AMZN NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: -2024-12-13 02:01:32,712 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,712 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:32,712 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,912 - DEBUG - queue.get: empty -2024-12-13 02:01:32,912 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,912 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:32,912 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,934 - DEBUG - len 203 raw:b"\x00\x00\x00\xc74\x002\x001005\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.TSLA NASDAQ.NMS/TOP/ALL\x00\x00"| -2024-12-13 02:01:32,934 - DEBUG - reader loop, recvd size 203 -2024-12-13 02:01:32,934 - DEBUG - read_msg: size: 199 -2024-12-13 02:01:32,934 - DEBUG - size:199 msg.size:199 msg:|b''| buf:|| -2024-12-13 02:01:32,934 - DEBUG - fields (b'4', b'2', b'1005', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.TSLA NASDAQ.NMS/TOP/ALL", b'') -2024-12-13 02:01:32,934 - DEBUG - decode b'2' -2024-12-13 02:01:32,934 - DEBUG - decode b'1005' -2024-12-13 02:01:32,934 - DEBUG - decode b'10089' -2024-12-13 02:01:32,934 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.TSLA NASDAQ.NMS/TOP/ALL" -2024-12-13 02:01:32,934 - DEBUG - decode b'' -2024-12-13 02:01:32,934 - ERROR - Error. ReqId: 1005, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.TSLA NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: -2024-12-13 02:01:32,935 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:32,935 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:32,935 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,135 - DEBUG - queue.get: empty -2024-12-13 02:01:33,135 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,135 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:33,135 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,335 - DEBUG - queue.get: empty -2024-12-13 02:01:33,335 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,335 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:33,335 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,536 - DEBUG - queue.get: empty -2024-12-13 02:01:33,536 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,536 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:33,536 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,736 - DEBUG - queue.get: empty -2024-12-13 02:01:33,736 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,736 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:33,736 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,935 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) -2024-12-13 02:01:33,935 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:33,936 - DEBUG - queue.get: empty -2024-12-13 02:01:33,936 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:33,936 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:33,936 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,137 - DEBUG - queue.get: empty -2024-12-13 02:01:34,137 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,137 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:34,137 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,337 - DEBUG - queue.get: empty -2024-12-13 02:01:34,337 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,337 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:34,337 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,537 - DEBUG - queue.get: empty -2024-12-13 02:01:34,538 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,538 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:34,538 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,738 - DEBUG - queue.get: empty -2024-12-13 02:01:34,738 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,738 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:34,738 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,937 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) -2024-12-13 02:01:34,937 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:34,938 - DEBUG - queue.get: empty -2024-12-13 02:01:34,938 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:34,938 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:34,938 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,139 - DEBUG - queue.get: empty -2024-12-13 02:01:35,139 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,139 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:35,139 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,339 - DEBUG - queue.get: empty -2024-12-13 02:01:35,339 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,339 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:35,339 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,539 - DEBUG - queue.get: empty -2024-12-13 02:01:35,539 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,539 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:35,539 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,740 - DEBUG - queue.get: empty -2024-12-13 02:01:35,740 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,740 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:35,740 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,938 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) -2024-12-13 02:01:35,938 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:35,940 - DEBUG - queue.get: empty -2024-12-13 02:01:35,940 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:35,940 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:35,940 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,141 - DEBUG - queue.get: empty -2024-12-13 02:01:36,141 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,141 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:36,141 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,341 - DEBUG - queue.get: empty -2024-12-13 02:01:36,341 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,341 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:36,341 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,541 - DEBUG - queue.get: empty -2024-12-13 02:01:36,541 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,541 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:36,541 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,742 - DEBUG - queue.get: empty -2024-12-13 02:01:36,742 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,742 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:36,742 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,939 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) -2024-12-13 02:01:36,939 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:36,942 - DEBUG - queue.get: empty -2024-12-13 02:01:36,942 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:36,942 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:36,942 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,143 - DEBUG - queue.get: empty -2024-12-13 02:01:37,143 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,143 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:37,143 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,343 - DEBUG - queue.get: empty -2024-12-13 02:01:37,343 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,343 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:37,343 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,543 - DEBUG - queue.get: empty -2024-12-13 02:01:37,543 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,543 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:37,543 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,744 - DEBUG - queue.get: empty -2024-12-13 02:01:37,744 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,744 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:37,744 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,941 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) -2024-12-13 02:01:37,941 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:37,944 - DEBUG - queue.get: empty -2024-12-13 02:01:37,944 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:37,944 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:37,944 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,144 - DEBUG - queue.get: empty -2024-12-13 02:01:38,144 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,144 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:38,145 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,345 - DEBUG - queue.get: empty -2024-12-13 02:01:38,345 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,345 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:38,345 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,545 - DEBUG - queue.get: empty -2024-12-13 02:01:38,545 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,545 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:38,545 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,746 - DEBUG - queue.get: empty -2024-12-13 02:01:38,746 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,746 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:38,746 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,942 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) -2024-12-13 02:01:38,942 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:38,946 - DEBUG - queue.get: empty -2024-12-13 02:01:38,946 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:38,946 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:38,946 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,146 - DEBUG - queue.get: empty -2024-12-13 02:01:39,146 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,146 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:39,146 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,346 - DEBUG - queue.get: empty -2024-12-13 02:01:39,347 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,347 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:39,347 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,547 - DEBUG - queue.get: empty -2024-12-13 02:01:39,547 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,547 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:39,547 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,747 - DEBUG - queue.get: empty -2024-12-13 02:01:39,747 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,747 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:39,747 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,942 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) -2024-12-13 02:01:39,942 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:39,948 - DEBUG - queue.get: empty -2024-12-13 02:01:39,948 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:39,948 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:39,948 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,148 - DEBUG - queue.get: empty -2024-12-13 02:01:40,148 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,148 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:40,148 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,348 - DEBUG - queue.get: empty -2024-12-13 02:01:40,348 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,348 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:40,348 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,549 - DEBUG - queue.get: empty -2024-12-13 02:01:40,549 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,549 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:40,549 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,749 - DEBUG - queue.get: empty -2024-12-13 02:01:40,749 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,749 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:40,749 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,943 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) -2024-12-13 02:01:40,943 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:40,949 - DEBUG - queue.get: empty -2024-12-13 02:01:40,949 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:40,949 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:40,949 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,150 - DEBUG - queue.get: empty -2024-12-13 02:01:41,150 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,150 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:41,150 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,350 - DEBUG - queue.get: empty -2024-12-13 02:01:41,350 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,350 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:41,350 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,550 - DEBUG - queue.get: empty -2024-12-13 02:01:41,550 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,550 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:41,550 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,751 - DEBUG - queue.get: empty -2024-12-13 02:01:41,751 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,751 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:41,751 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,945 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) -2024-12-13 02:01:41,945 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:41,951 - DEBUG - queue.get: empty -2024-12-13 02:01:41,951 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:41,951 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:41,951 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:42,152 - DEBUG - queue.get: empty -2024-12-13 02:01:42,152 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:42,152 - DEBUG - conn:1 queue.sz:0 -2024-12-13 02:01:42,152 - DEBUG - 139881771299920 isConn: 2, connConnected: True -2024-12-13 02:01:42,322 - WARNING - Timeout while waiting for market data. -2024-12-13 02:01:42,322 - DEBUG - 139881771299920 connState: 2 -> 0 -2024-12-13 02:01:42,322 - INFO - disconnecting -2024-12-13 02:01:42,322 - DEBUG - disconnecting -2024-12-13 02:01:42,322 - DEBUG - disconnected -2024-12-13 02:01:42,322 - INFO - Connection to IB Gateway closed. -2024-12-13 02:01:42,323 - DEBUG - 139881771299920 connState: None -> 0 -2024-12-13 02:01:42,352 - DEBUG - queue.get: empty -2024-12-13 02:01:42,352 - DEBUG - 139881771299920 isConn: 0, connConnected: None -2024-12-13 02:01:42,352 - DEBUG - conn:0 queue.sz:0 -2024-12-13 02:01:42,352 - DEBUG - 139881771299920 isConn: 0, connConnected: None -2024-12-13 02:01:42,352 - DEBUG - 139881771299920 connState: 0 -> 0 -2024-12-13 02:01:42,946 - DEBUG - socket broken, disconnecting -2024-12-13 02:01:42,946 - DEBUG - reader loop, recvd size 0 -2024-12-13 02:01:42,946 - DEBUG - EReader thread finished -2024-12-13 02:01:44,323 - INFO - TestStockRetriever completed. +2024-12-13 22:17:07,314 - DEBUG - 140417584002128 connState: None -> 0 +2024-12-13 22:17:07,314 - DEBUG - 140417584002128 connState: None -> 0 +2024-12-13 22:17:07,314 - DEBUG - Connecting to 127.0.0.1:4002 w/ id:1 +2024-12-13 22:17:07,314 - DEBUG - 140417584002128 connState: 0 -> 1 +2024-12-13 22:17:07,314 - DEBUG - msg b'\x00\x00\x00\tv100..193' +2024-12-13 22:17:07,314 - DEBUG - REQUEST b'API\x00\x00\x00\x00\tv100..193' +2024-12-13 22:17:07,314 - DEBUG - acquiring lock +2024-12-13 22:17:07,314 - DEBUG - acquired lock +2024-12-13 22:17:07,314 - DEBUG - releasing lock +2024-12-13 22:17:07,314 - DEBUG - release lock +2024-12-13 22:17:07,314 - DEBUG - sendMsg: sent: 17 +2024-12-13 22:17:07,315 - DEBUG - no fields +2024-12-13 22:17:07,351 - DEBUG - len 30 raw:b'\x00\x00\x00\x1a187\x0020241213 22:17:07 EST\x00'| +2024-12-13 22:17:07,351 - DEBUG - ANSWER b'\x00\x00\x00\x1a187\x0020241213 22:17:07 EST\x00' +2024-12-13 22:17:07,351 - DEBUG - read_msg: size: 26 +2024-12-13 22:17:07,351 - DEBUG - size:26 msg:b'187\x0020241213 22:17:07 EST\x00' rest:b''| +2024-12-13 22:17:07,351 - DEBUG - fields (b'187', b'20241213 22:17:07 EST') +2024-12-13 22:17:07,351 - DEBUG - ANSWER Version:187 time:b'20241213 22:17:07 EST' +2024-12-13 22:17:07,351 - DEBUG - 140417584002128 connState: 1 -> 2 +2024-12-13 22:17:07,352 - DEBUG - EReader thread started +2024-12-13 22:17:07,352 - INFO - sent startApi +2024-12-13 22:17:07,352 - INFO - REQUEST startApi {} +2024-12-13 22:17:07,352 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,352 - INFO - SENDING startApi b'\x00\x00\x00\x0871\x002\x001\x00\x00' +2024-12-13 22:17:07,352 - DEBUG - acquiring lock +2024-12-13 22:17:07,352 - DEBUG - acquired lock +2024-12-13 22:17:07,352 - DEBUG - releasing lock +2024-12-13 22:17:07,352 - DEBUG - release lock +2024-12-13 22:17:07,352 - DEBUG - sendMsg: sent: 12 +2024-12-13 22:17:07,352 - INFO - ANSWER connectAck {} +2024-12-13 22:17:07,352 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,369 - DEBUG - len 19 raw:b'\x00\x00\x00\x0f15\x001\x00DUE064818\x00'| +2024-12-13 22:17:07,369 - DEBUG - reader loop, recvd size 19 +2024-12-13 22:17:07,369 - DEBUG - read_msg: size: 15 +2024-12-13 22:17:07,369 - DEBUG - size:15 msg.size:15 msg:|b''| buf:|| +2024-12-13 22:17:07,370 - DEBUG - fields (b'15', b'1', b'DUE064818') +2024-12-13 22:17:07,370 - DEBUG - In interpret(), handleInfo: wrap: meth:None prms:OrderedDict([('self', ), ('accountsList', )]) +2024-12-13 22:17:07,370 - DEBUG - field b'DUE064818' +2024-12-13 22:17:07,370 - DEBUG - arg DUE064818 type +2024-12-13 22:17:07,370 - DEBUG - calling > with <__main__.TestStockRetriever object at 0x7fb58436f450> ['DUE064818'] +2024-12-13 22:17:07,370 - INFO - ANSWER managedAccounts {'accountsList': 'DUE064818'} +2024-12-13 22:17:07,370 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,370 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:07,370 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,410 - DEBUG - len 185 raw:b'\x00\x00\x00\x069\x001\x001\x00\x00\x00\x0064\x002\x00-1\x002104\x00Market data farm connection is OK:usfarm\x00\x00\x00\x00\x0044\x002\x00-1\x002106\x00HMDS data farm connection is OK:ushmds\x00\x00\x00\x00\x0094\x002\x00-1\x002158\x00Sec-def data farm connection is OK:secdefnj\x00\x00'| +2024-12-13 22:17:07,410 - DEBUG - reader loop, recvd size 185 +2024-12-13 22:17:07,410 - DEBUG - read_msg: size: 6 +2024-12-13 22:17:07,410 - DEBUG - size:6 msg.size:6 msg:|b'\x00\x00\x0064\x002\x00-1\x002104\x00Market data farm connection is OK:usfarm\x00\x00\x00\x00\x0044\x002\x00-1\x002106\x00HMDS data farm connection is OK:ushmds\x00\x00\x00\x00\x0094\x002\x00-1\x002158\x00Sec-def data farm connection is OK:secdefnj\x00\x00'| buf:|| +2024-12-13 22:17:07,410 - DEBUG - read_msg: size: 54 +2024-12-13 22:17:07,410 - DEBUG - size:54 msg.size:54 msg:|b'\x00\x00\x0044\x002\x00-1\x002106\x00HMDS data farm connection is OK:ushmds\x00\x00\x00\x00\x0094\x002\x00-1\x002158\x00Sec-def data farm connection is OK:secdefnj\x00\x00'| buf:|| +2024-12-13 22:17:07,411 - DEBUG - fields (b'9', b'1', b'1') +2024-12-13 22:17:07,411 - DEBUG - read_msg: size: 52 +2024-12-13 22:17:07,411 - DEBUG - In interpret(), handleInfo: wrap: meth:None prms:OrderedDict([('self', ), ('orderId', )]) +2024-12-13 22:17:07,412 - DEBUG - size:52 msg.size:52 msg:|b'\x00\x00\x0094\x002\x00-1\x002158\x00Sec-def data farm connection is OK:secdefnj\x00\x00'| buf:|| +2024-12-13 22:17:07,412 - DEBUG - field b'1' +2024-12-13 22:17:07,412 - DEBUG - read_msg: size: 57 +2024-12-13 22:17:07,413 - DEBUG - arg 1 type +2024-12-13 22:17:07,413 - DEBUG - calling > with <__main__.TestStockRetriever object at 0x7fb58436f450> [1] +2024-12-13 22:17:07,413 - DEBUG - size:57 msg.size:57 msg:|b''| buf:|| +2024-12-13 22:17:07,413 - INFO - NextValidId received: 1 +2024-12-13 22:17:07,414 - INFO - Requesting market data for symbols... +2024-12-13 22:17:07,414 - INFO - REQUEST reqMktData {'reqId': 1001, 'contract': 140417587752272: 0,AAPL,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} +2024-12-13 22:17:07,414 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,414 - INFO - SENDING reqMktData b'\x00\x00\x0011\x0011\x001001\x000\x00AAPL\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' +2024-12-13 22:17:07,414 - DEBUG - acquiring lock +2024-12-13 22:17:07,414 - DEBUG - acquired lock +2024-12-13 22:17:07,414 - DEBUG - releasing lock +2024-12-13 22:17:07,414 - DEBUG - release lock +2024-12-13 22:17:07,414 - DEBUG - sendMsg: sent: 53 +2024-12-13 22:17:07,414 - DEBUG - Requested market data for AAPL with ReqId: 1001 +2024-12-13 22:17:07,414 - INFO - REQUEST reqMktData {'reqId': 1002, 'contract': 140417584111056: 0,MSFT,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} +2024-12-13 22:17:07,414 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,414 - INFO - SENDING reqMktData b'\x00\x00\x0011\x0011\x001002\x000\x00MSFT\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' +2024-12-13 22:17:07,414 - DEBUG - acquiring lock +2024-12-13 22:17:07,414 - DEBUG - acquired lock +2024-12-13 22:17:07,414 - DEBUG - releasing lock +2024-12-13 22:17:07,414 - DEBUG - release lock +2024-12-13 22:17:07,414 - DEBUG - sendMsg: sent: 53 +2024-12-13 22:17:07,415 - DEBUG - Requested market data for MSFT with ReqId: 1002 +2024-12-13 22:17:07,415 - INFO - REQUEST reqMktData {'reqId': 1003, 'contract': 140417584110928: 0,GOOGL,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} +2024-12-13 22:17:07,415 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,415 - INFO - SENDING reqMktData b'\x00\x00\x0021\x0011\x001003\x000\x00GOOGL\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' +2024-12-13 22:17:07,415 - DEBUG - acquiring lock +2024-12-13 22:17:07,415 - DEBUG - acquired lock +2024-12-13 22:17:07,415 - DEBUG - releasing lock +2024-12-13 22:17:07,415 - DEBUG - release lock +2024-12-13 22:17:07,415 - DEBUG - sendMsg: sent: 54 +2024-12-13 22:17:07,415 - DEBUG - Requested market data for GOOGL with ReqId: 1003 +2024-12-13 22:17:07,415 - INFO - REQUEST reqMktData {'reqId': 1004, 'contract': 140417584111184: 0,AMZN,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} +2024-12-13 22:17:07,415 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,415 - INFO - SENDING reqMktData b'\x00\x00\x0011\x0011\x001004\x000\x00AMZN\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' +2024-12-13 22:17:07,415 - DEBUG - acquiring lock +2024-12-13 22:17:07,415 - DEBUG - acquired lock +2024-12-13 22:17:07,415 - DEBUG - releasing lock +2024-12-13 22:17:07,415 - DEBUG - release lock +2024-12-13 22:17:07,415 - DEBUG - sendMsg: sent: 53 +2024-12-13 22:17:07,415 - DEBUG - Requested market data for AMZN with ReqId: 1004 +2024-12-13 22:17:07,415 - INFO - REQUEST reqMktData {'reqId': 1005, 'contract': 140417584111440: 0,TSLA,STK,,,0,,,SMART,,USD,,,False,,,,combo:, 'genericTickList': '', 'snapshot': False, 'regulatorySnapshot': False, 'mktDataOptions': []} +2024-12-13 22:17:07,415 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,415 - INFO - SENDING reqMktData b'\x00\x00\x0011\x0011\x001005\x000\x00TSLA\x00STK\x00\x000.0\x00\x00\x00SMART\x00\x00USD\x00\x00\x000\x00\x000\x000\x00\x00' +2024-12-13 22:17:07,415 - DEBUG - acquiring lock +2024-12-13 22:17:07,415 - DEBUG - acquired lock +2024-12-13 22:17:07,415 - DEBUG - releasing lock +2024-12-13 22:17:07,415 - DEBUG - release lock +2024-12-13 22:17:07,415 - DEBUG - sendMsg: sent: 53 +2024-12-13 22:17:07,415 - DEBUG - Requested market data for TSLA with ReqId: 1005 +2024-12-13 22:17:07,415 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,415 - DEBUG - conn:1 queue.sz:3 +2024-12-13 22:17:07,415 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,416 - DEBUG - fields (b'4', b'2', b'-1', b'2104', b'Market data farm connection is OK:usfarm', b'') +2024-12-13 22:17:07,416 - DEBUG - decode b'2' +2024-12-13 22:17:07,416 - DEBUG - decode b'-1' +2024-12-13 22:17:07,416 - DEBUG - decode b'2104' +2024-12-13 22:17:07,416 - DEBUG - decode b'Market data farm connection is OK:usfarm' +2024-12-13 22:17:07,416 - DEBUG - decode b'' +2024-12-13 22:17:07,416 - ERROR - Error. ReqId: -1, Code: 2104, Msg: Market data farm connection is OK:usfarm, Advanced Order Reject JSON: +2024-12-13 22:17:07,416 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,416 - DEBUG - conn:1 queue.sz:2 +2024-12-13 22:17:07,416 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,416 - DEBUG - fields (b'4', b'2', b'-1', b'2106', b'HMDS data farm connection is OK:ushmds', b'') +2024-12-13 22:17:07,416 - DEBUG - decode b'2' +2024-12-13 22:17:07,416 - DEBUG - decode b'-1' +2024-12-13 22:17:07,416 - DEBUG - decode b'2106' +2024-12-13 22:17:07,416 - DEBUG - decode b'HMDS data farm connection is OK:ushmds' +2024-12-13 22:17:07,416 - DEBUG - decode b'' +2024-12-13 22:17:07,416 - ERROR - Error. ReqId: -1, Code: 2106, Msg: HMDS data farm connection is OK:ushmds, Advanced Order Reject JSON: +2024-12-13 22:17:07,416 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,416 - DEBUG - conn:1 queue.sz:1 +2024-12-13 22:17:07,416 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,416 - DEBUG - fields (b'4', b'2', b'-1', b'2158', b'Sec-def data farm connection is OK:secdefnj', b'') +2024-12-13 22:17:07,416 - DEBUG - decode b'2' +2024-12-13 22:17:07,416 - DEBUG - decode b'-1' +2024-12-13 22:17:07,416 - DEBUG - decode b'2158' +2024-12-13 22:17:07,416 - DEBUG - decode b'Sec-def data farm connection is OK:secdefnj' +2024-12-13 22:17:07,416 - DEBUG - decode b'' +2024-12-13 22:17:07,416 - ERROR - Error. ReqId: -1, Code: 2158, Msg: Sec-def data farm connection is OK:secdefnj, Advanced Order Reject JSON: +2024-12-13 22:17:07,416 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,416 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:07,416 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,617 - DEBUG - queue.get: empty +2024-12-13 22:17:07,617 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,617 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:07,617 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,809 - DEBUG - len 203 raw:b"\x00\x00\x00\xc74\x002\x001001\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AAPL NASDAQ.NMS/TOP/ALL\x00\x00"| +2024-12-13 22:17:07,809 - DEBUG - reader loop, recvd size 203 +2024-12-13 22:17:07,809 - DEBUG - read_msg: size: 199 +2024-12-13 22:17:07,809 - DEBUG - size:199 msg.size:199 msg:|b''| buf:|| +2024-12-13 22:17:07,809 - DEBUG - fields (b'4', b'2', b'1001', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AAPL NASDAQ.NMS/TOP/ALL", b'') +2024-12-13 22:17:07,809 - DEBUG - decode b'2' +2024-12-13 22:17:07,809 - DEBUG - decode b'1001' +2024-12-13 22:17:07,809 - DEBUG - decode b'10089' +2024-12-13 22:17:07,810 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AAPL NASDAQ.NMS/TOP/ALL" +2024-12-13 22:17:07,810 - DEBUG - decode b'' +2024-12-13 22:17:07,810 - ERROR - Error. ReqId: 1001, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AAPL NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: +2024-12-13 22:17:07,810 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,810 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:07,810 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,860 - DEBUG - len 203 raw:b"\x00\x00\x00\xc74\x002\x001002\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.MSFT NASDAQ.NMS/TOP/ALL\x00\x00"| +2024-12-13 22:17:07,860 - DEBUG - reader loop, recvd size 203 +2024-12-13 22:17:07,860 - DEBUG - read_msg: size: 199 +2024-12-13 22:17:07,861 - DEBUG - size:199 msg.size:199 msg:|b''| buf:|| +2024-12-13 22:17:07,861 - DEBUG - fields (b'4', b'2', b'1002', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.MSFT NASDAQ.NMS/TOP/ALL", b'') +2024-12-13 22:17:07,861 - DEBUG - decode b'2' +2024-12-13 22:17:07,861 - DEBUG - decode b'1002' +2024-12-13 22:17:07,861 - DEBUG - decode b'10089' +2024-12-13 22:17:07,861 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.MSFT NASDAQ.NMS/TOP/ALL" +2024-12-13 22:17:07,861 - DEBUG - decode b'' +2024-12-13 22:17:07,861 - ERROR - Error. ReqId: 1002, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.MSFT NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: +2024-12-13 22:17:07,861 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,862 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:07,862 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,864 - DEBUG - len 204 raw:b"\x00\x00\x00\xc84\x002\x001003\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.GOOGL NASDAQ.NMS/TOP/ALL\x00\x00"| +2024-12-13 22:17:07,864 - DEBUG - reader loop, recvd size 204 +2024-12-13 22:17:07,865 - DEBUG - read_msg: size: 200 +2024-12-13 22:17:07,865 - DEBUG - size:200 msg.size:200 msg:|b''| buf:|| +2024-12-13 22:17:07,865 - DEBUG - fields (b'4', b'2', b'1003', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.GOOGL NASDAQ.NMS/TOP/ALL", b'') +2024-12-13 22:17:07,865 - DEBUG - decode b'2' +2024-12-13 22:17:07,865 - DEBUG - decode b'1003' +2024-12-13 22:17:07,865 - DEBUG - decode b'10089' +2024-12-13 22:17:07,865 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.GOOGL NASDAQ.NMS/TOP/ALL" +2024-12-13 22:17:07,865 - DEBUG - decode b'' +2024-12-13 22:17:07,866 - ERROR - Error. ReqId: 1003, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.GOOGL NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: +2024-12-13 22:17:07,866 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,866 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:07,866 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,866 - DEBUG - len 203 raw:b"\x00\x00\x00\xc74\x002\x001004\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AMZN NASDAQ.NMS/TOP/ALL\x00\x00"| +2024-12-13 22:17:07,867 - DEBUG - reader loop, recvd size 203 +2024-12-13 22:17:07,867 - DEBUG - read_msg: size: 199 +2024-12-13 22:17:07,867 - DEBUG - size:199 msg.size:199 msg:|b''| buf:|| +2024-12-13 22:17:07,867 - DEBUG - fields (b'4', b'2', b'1004', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AMZN NASDAQ.NMS/TOP/ALL", b'') +2024-12-13 22:17:07,867 - DEBUG - decode b'2' +2024-12-13 22:17:07,867 - DEBUG - decode b'1004' +2024-12-13 22:17:07,867 - DEBUG - decode b'10089' +2024-12-13 22:17:07,867 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AMZN NASDAQ.NMS/TOP/ALL" +2024-12-13 22:17:07,867 - DEBUG - decode b'' +2024-12-13 22:17:07,868 - ERROR - Error. ReqId: 1004, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.AMZN NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: +2024-12-13 22:17:07,868 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:07,868 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:07,868 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,038 - DEBUG - len 203 raw:b"\x00\x00\x00\xc74\x002\x001005\x0010089\x00Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.TSLA NASDAQ.NMS/TOP/ALL\x00\x00"| +2024-12-13 22:17:08,038 - DEBUG - reader loop, recvd size 203 +2024-12-13 22:17:08,039 - DEBUG - read_msg: size: 199 +2024-12-13 22:17:08,039 - DEBUG - size:199 msg.size:199 msg:|b''| buf:|| +2024-12-13 22:17:08,039 - DEBUG - fields (b'4', b'2', b'1005', b'10089', b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.TSLA NASDAQ.NMS/TOP/ALL", b'') +2024-12-13 22:17:08,039 - DEBUG - decode b'2' +2024-12-13 22:17:08,039 - DEBUG - decode b'1005' +2024-12-13 22:17:08,039 - DEBUG - decode b'10089' +2024-12-13 22:17:08,039 - DEBUG - decode b"Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.TSLA NASDAQ.NMS/TOP/ALL" +2024-12-13 22:17:08,039 - DEBUG - decode b'' +2024-12-13 22:17:08,040 - ERROR - Error. ReqId: 1005, Code: 10089, Msg: Requested market data requires additional subscription for API. See link in 'Market Data Connections' dialog for more details.Delayed market data is available.TSLA NASDAQ.NMS/TOP/ALL, Advanced Order Reject JSON: +2024-12-13 22:17:08,040 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,040 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:08,040 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,240 - DEBUG - queue.get: empty +2024-12-13 22:17:08,241 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,241 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:08,241 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,442 - DEBUG - queue.get: empty +2024-12-13 22:17:08,442 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,442 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:08,443 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,643 - DEBUG - queue.get: empty +2024-12-13 22:17:08,644 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,644 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:08,644 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,845 - DEBUG - queue.get: empty +2024-12-13 22:17:08,845 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:08,846 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:08,846 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,040 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) +2024-12-13 22:17:09,040 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:09,046 - DEBUG - queue.get: empty +2024-12-13 22:17:09,046 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,046 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:09,046 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,246 - DEBUG - queue.get: empty +2024-12-13 22:17:09,247 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,247 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:09,247 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,447 - DEBUG - queue.get: empty +2024-12-13 22:17:09,448 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,448 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:09,448 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,648 - DEBUG - queue.get: empty +2024-12-13 22:17:09,648 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,648 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:09,648 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,849 - DEBUG - queue.get: empty +2024-12-13 22:17:09,849 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:09,849 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:09,850 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,041 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) +2024-12-13 22:17:10,042 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:10,050 - DEBUG - queue.get: empty +2024-12-13 22:17:10,051 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,051 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:10,051 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,252 - DEBUG - queue.get: empty +2024-12-13 22:17:10,252 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,252 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:10,253 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,453 - DEBUG - queue.get: empty +2024-12-13 22:17:10,454 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,454 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:10,454 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,654 - DEBUG - queue.get: empty +2024-12-13 22:17:10,655 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,655 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:10,655 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,856 - DEBUG - queue.get: empty +2024-12-13 22:17:10,856 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:10,856 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:10,856 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,044 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) +2024-12-13 22:17:11,044 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:11,057 - DEBUG - queue.get: empty +2024-12-13 22:17:11,057 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,057 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:11,058 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,258 - DEBUG - queue.get: empty +2024-12-13 22:17:11,259 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,259 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:11,259 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,460 - DEBUG - queue.get: empty +2024-12-13 22:17:11,460 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,460 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:11,460 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,661 - DEBUG - queue.get: empty +2024-12-13 22:17:11,661 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,661 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:11,662 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,862 - DEBUG - queue.get: empty +2024-12-13 22:17:11,863 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:11,863 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:11,863 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,045 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) +2024-12-13 22:17:12,046 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:12,064 - DEBUG - queue.get: empty +2024-12-13 22:17:12,064 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,064 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:12,065 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,265 - DEBUG - queue.get: empty +2024-12-13 22:17:12,266 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,266 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:12,266 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,467 - DEBUG - queue.get: empty +2024-12-13 22:17:12,467 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,467 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:12,467 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,668 - DEBUG - queue.get: empty +2024-12-13 22:17:12,668 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,668 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:12,668 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,869 - DEBUG - queue.get: empty +2024-12-13 22:17:12,869 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:12,870 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:12,870 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,047 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) +2024-12-13 22:17:13,048 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:13,070 - DEBUG - queue.get: empty +2024-12-13 22:17:13,070 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,071 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:13,071 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,271 - DEBUG - queue.get: empty +2024-12-13 22:17:13,271 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,272 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:13,272 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,472 - DEBUG - queue.get: empty +2024-12-13 22:17:13,473 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,473 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:13,473 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,674 - DEBUG - queue.get: empty +2024-12-13 22:17:13,674 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,674 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:13,674 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,875 - DEBUG - queue.get: empty +2024-12-13 22:17:13,875 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:13,875 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:13,876 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,049 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) +2024-12-13 22:17:14,050 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:14,076 - DEBUG - queue.get: empty +2024-12-13 22:17:14,077 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,077 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:14,077 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,278 - DEBUG - queue.get: empty +2024-12-13 22:17:14,278 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,278 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:14,279 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,479 - DEBUG - queue.get: empty +2024-12-13 22:17:14,480 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,480 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:14,480 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,680 - DEBUG - queue.get: empty +2024-12-13 22:17:14,681 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,681 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:14,681 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,882 - DEBUG - queue.get: empty +2024-12-13 22:17:14,882 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:14,882 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:14,883 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,050 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) +2024-12-13 22:17:15,051 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:15,083 - DEBUG - queue.get: empty +2024-12-13 22:17:15,083 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,083 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:15,084 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,284 - DEBUG - queue.get: empty +2024-12-13 22:17:15,285 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,285 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:15,285 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,485 - DEBUG - queue.get: empty +2024-12-13 22:17:15,486 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,486 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:15,486 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,686 - DEBUG - queue.get: empty +2024-12-13 22:17:15,687 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,687 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:15,687 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,888 - DEBUG - queue.get: empty +2024-12-13 22:17:15,888 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:15,888 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:15,888 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,052 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) +2024-12-13 22:17:16,052 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:16,089 - DEBUG - queue.get: empty +2024-12-13 22:17:16,089 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,089 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:16,089 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,290 - DEBUG - queue.get: empty +2024-12-13 22:17:16,290 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,290 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:16,291 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,491 - DEBUG - queue.get: empty +2024-12-13 22:17:16,491 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,492 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:16,492 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,692 - DEBUG - queue.get: empty +2024-12-13 22:17:16,693 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,693 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:16,693 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,893 - DEBUG - queue.get: empty +2024-12-13 22:17:16,894 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:16,894 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:16,894 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:17,054 - DEBUG - socket timeout from recvMsg (, TimeoutError('timed out'), ) +2024-12-13 22:17:17,054 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:17,095 - DEBUG - queue.get: empty +2024-12-13 22:17:17,095 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:17,095 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:17,096 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:17,296 - DEBUG - queue.get: empty +2024-12-13 22:17:17,297 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:17,297 - DEBUG - conn:1 queue.sz:0 +2024-12-13 22:17:17,297 - DEBUG - 140417584002128 isConn: 2, connConnected: True +2024-12-13 22:17:17,353 - WARNING - Timeout while waiting for market data. +2024-12-13 22:17:17,353 - DEBUG - 140417584002128 connState: 2 -> 0 +2024-12-13 22:17:17,353 - INFO - disconnecting +2024-12-13 22:17:17,353 - DEBUG - disconnecting +2024-12-13 22:17:17,353 - DEBUG - disconnected +2024-12-13 22:17:17,353 - INFO - Connection to IB Gateway closed. +2024-12-13 22:17:17,353 - DEBUG - 140417584002128 connState: None -> 0 +2024-12-13 22:17:17,498 - DEBUG - queue.get: empty +2024-12-13 22:17:17,498 - DEBUG - 140417584002128 isConn: 0, connConnected: None +2024-12-13 22:17:17,498 - DEBUG - conn:0 queue.sz:0 +2024-12-13 22:17:17,499 - DEBUG - 140417584002128 isConn: 0, connConnected: None +2024-12-13 22:17:17,499 - DEBUG - 140417584002128 connState: 0 -> 0 +2024-12-13 22:17:18,055 - DEBUG - socket broken, disconnecting +2024-12-13 22:17:18,055 - DEBUG - reader loop, recvd size 0 +2024-12-13 22:17:18,055 - DEBUG - EReader thread finished +2024-12-13 22:17:19,354 - INFO - TestStockRetriever completed.