System.InvalidOperationException: Sequence contains no matching element
Closed this issue · 12 comments
IgitBuh commented
I have finally received my ism7 module and have set up ism7mqtt 0.0.13 from scratch, created the parameter.json and am trying to use the official docker image.
However, after configuring the container, starting it throws the following error and I have no idea what this could mean:
System.InvalidOperationException: Sequence contains no matching element
at System.Linq.ThrowHelper.ThrowNoMatchException()
at ism7mqtt.Ism7Config.AddDevice(String ip, String ba) in /app/ISM7/Ism7Config.cs:line 76
at ism7mqtt.Ism7Client.LoadInitialValuesAsync(CancellationToken cancellationToken) in /app/ISM7/Ism7Client.cs:line 281
at ism7mqtt.ResponseDispatcher.DispatchAsync(IResponse response, CancellationToken cancellationToken) in /app/ISM7/ResponseDispatcher.cs:line 32
at ism7mqtt.Ism7Client.ReadPipeAsync(PipeReader source, CancellationToken cancellationToken) in /app/ISM7/Ism7Client.cs:line 213
How can I solve this or what can I do to find the reason for this error message?
zivillian commented
Can you share your parameter.json and the debug log?
IgitBuh commented
Yes sure:
parameter.json
{
"Devices": [
{
"ReadBusAddress": "0x00",
"DeviceTemplateId": 190000,
"Parameter": [
190001,
190003,
190007,
190011,
190015,
190019,
190000,
190021,
190002,
190004,
190012,
190014,
190016,
190020
]
},
{
"ReadBusAddress": "0x8",
"DeviceTemplateId": 270000,
"Parameter": [
270039,
270098,
270157,
270020,
270079,
270138,
270001,
270060,
270119,
270041,
270100,
270159,
270022,
270081,
270140,
270003,
270062,
270121,
270043,
270102,
270024,
270083,
270142,
270005,
270064,
270123,
270045,
270104,
270026,
270085,
270144,
270007,
270066,
270125,
270047,
270106,
270028,
270087,
270146,
270009,
270068,
270049,
270108,
270030,
270089,
270011,
270070,
270129,
270051,
270110,
270032,
270091,
270150,
270013,
270072,
270053,
270112,
270034,
270152,
270015,
270074,
270055,
270114,
270036,
270017,
270076,
270135,
270057,
270116,
270038,
270097,
270156,
270019,
270078,
270137,
270000,
270059,
270118,
270040,
270099,
270021,
270080,
270139,
270002,
270061,
270120,
270042,
270101,
270023,
270082,
270141,
270004,
270063,
270122,
270044,
270103,
270025,
270084,
270143,
270006,
270065,
270124,
270046,
270105,
270027,
270086,
270145,
270008,
270067,
270126,
270048,
270107,
270029,
270088,
270010,
270069,
270128,
270050,
270109,
270031,
270090,
270149,
270012,
270071,
270130,
270052,
270111,
270033,
270092,
270151,
270014,
270073,
270113,
270035,
270016,
270075,
270134,
270056,
270115,
270037,
270096,
270155,
270018,
270077,
270136,
270058,
270117
]
},
{
"ReadBusAddress": "0x35",
"DeviceTemplateId": 220000,
"Parameter": [
220104,
220026,
330008,
220106,
220028,
330010,
220108,
220030,
220129,
220032,
220091,
220034,
220095,
330001,
220040,
220021,
330003,
220042,
220023,
330005,
220044,
220103,
220025,
330007,
220046,
220105,
220027,
330009,
220126,
220107,
220029,
330011,
220128,
220031,
220130,
220033,
220035,
220134,
330000,
220117,
330002,
220001,
220022,
330004,
220043,
330006,
220045
]
},
{
"ReadBusAddress": "0x51",
"DeviceTemplateId": 40000,
"Parameter": [
40001,
40005,
40007,
40009,
40011,
40070,
40093,
40002,
40004,
40006,
40027,
40010,
40014
]
},
{
"ReadBusAddress": "0x35",
"DeviceTemplateId": 340000,
"Parameter": [
320014,
340004,
320016,
340006,
320018,
340008,
320020,
320001,
340029,
340010,
340050,
320003,
340031,
340012,
320005,
340033,
340014,
320007,
340035,
340016,
320009,
320011,
340001,
320013,
340041,
320015,
340043,
340005,
320017,
340045,
340026,
340007,
320019,
320000,
340009,
320002,
340011,
320004,
340032,
340013,
320006,
340015,
340055,
320008,
320010,
340000,
320012,
340040
]
},
{
"ReadBusAddress": "0x35",
"DeviceTemplateId": 350000,
"Parameter": [
320033,
350001,
320035,
320037,
320039,
320041,
320022,
350009,
320024,
320026,
320028,
350015,
320030,
320032,
320034,
320036,
350004,
320038,
320040,
320021,
320023,
320025,
350012,
320027,
350014,
320029,
350016,
320031
]
},
{
"ReadBusAddress": "0x85",
"DeviceTemplateId": 360000,
"Parameter": [
320073,
360014,
360132,
320075,
360016,
320077,
360136,
360058,
320079,
360001,
320081,
320083,
320064,
360005,
360085,
320066,
360007,
360066,
320068,
320070,
360148,
360011,
320072,
360150,
360013,
320074,
360015,
320076,
320078,
360000,
320080,
360042,
320082,
320063,
360004,
360084,
320065,
360006,
320067,
360145,
360008,
320069,
360147,
360010,
320071,
360149,
360012,
360130
]
}
]
}I noticed that the command prompt from creating the file was still open, so here's also the creation output:
output
2023-07-15 01:21:11.7592|INFO|LuCon.WebPortal.StandaloneService.NetworkConnector|Try IP-resolve for host:192.168.178.212
2023-07-15 01:21:12.0106|INFO|LuCon.WebPortal.StandaloneService.NetworkConnector|Try connect destination server -> 192.168.178.212:9092, Timeout:1000 ms
2023-07-15 01:21:13.8407|INFO|LuCon.SocketServer.SocketServerBase.GatewayContext|<GW:-1,0> Written XML, bytes:118, msg:<?xml version="1.0" encoding="utf-8"?>
<direct-logon-request>
<password>REMOVED</password>
</direct-logon-request>
2023-07-15 01:21:14.0336|INFO|LuCon.WebPortal.StandaloneService.NetworkConnector|Got XML msg:<?xml version="1.0" encoding="utf-8"?>
<direct-logon-response state="ok" sid="1">
<installationname>WolfLink</installationname>
<serialnumber>REMOVED2</serialnumber>
<date-time>2023-07-15T01:21:25</date-time>
<ism-softwareversion>300.10.1.300.300.1</ism-softwareversion>
<ism-hardwareversion>3.0</ism-hardwareversion>
<type>WOLFLink</type>
</direct-logon-response>
2023-07-15 01:21:14.0652|INFO|LuCon.WebPortal.StandaloneService.NetworkConnector|logonResponse:Errormsg:, State:ok
2023-07-15 01:21:14.3149|INFO|GW-1|<GW:1,0> Written XML, bytes:81, msg:<?xml version="1.0" encoding="utf-8"?>
<read-systemconfig-request sid="1" />
2023-07-15 01:21:14.3650|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:21:14.3650|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=4
2023-07-15 01:21:14.3650|TRACE|GW-1|<GW:1,0> lenToRead: 500
2023-07-15 01:21:14.3733|TRACE|GW-1|<GW:1,0> telegrType SystemconfigResponse
2023-07-15 01:21:14.3733|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:500 [6/500]
2023-07-15 01:21:14.4397|TRACE|GW-1|<GW:1,0> Got 266 bytes from stream, elapsedMs=73
2023-07-15 01:21:14.4397|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:234 [272/500]
2023-07-15 01:21:14.5197|TRACE|GW-1|<GW:1,0> Got 126 bytes from stream, elapsedMs=153
2023-07-15 01:21:14.5197|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:108 [398/500]
2023-07-15 01:21:14.5996|TRACE|GW-1|<GW:1,0> Got 108 bytes from stream, elapsedMs=233
2023-07-15 01:21:14.5996|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<read-systemconfig-response sid="1">
<gateway type="WOLFLink" softwareNumber="1" softwareVersion="300" wlan="false" g3="false"/>
<busconfig type="ebus">
<busDevices>
<busDevice ba="0x8" sv="0x1" sr="0x50" cfg="0x440" did="0x29"/>
<busDevice ba="0x35" sv="0x2" sr="0x4" cfg="0xC000" did="0x20"/>
<busDevice ba="0x51" sv="0x1" sr="0x0" cfg="0x10" did="0x14"/>
</busDevices>
</busconfig>
<modbusconfig>
<busDevices>
</busDevices>
</modbusconfig>
</read-systemconfig-response>
2023-07-15 01:21:14.6987|INFO|GW-1|<GW:1,-1> - ProcessSystemconfigResponse:SystemconfigResponse, SID:1
2023-07-15 01:21:14.8562|INFO|GW-1|<GW:1,-1> >>>>>>>>>>>> state:1, count:4{
{Device(-1), visible:True, ba:0x00, dt:GW, di:0 hdt:-1, sv:, sr:, wrsId:, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:190000, ConfigIndex:0, ConfigMask:0}
{Device(-2), visible:False, ba:0x8, dt:HG, di:1 hdt:-1, sv:0x1, sr:0x50, wrsId:0x29, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:270000, ConfigIndex:0, ConfigMask:1088}
{Device(-3), visible:False, ba:0x35, dt:BM, di:0 hdt:-1, sv:0x2, sr:0x4, wrsId:0x20, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:220000, ConfigIndex:0, ConfigMask:49152}
{Device(-4), visible:False, ba:0x51, dt:MM, di:1 hdt:-1, sv:0x1, sr:0x0, wrsId:0x14, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:40000, ConfigIndex:0, ConfigMask:16}}
<<<<<<<<<<<<
2023-07-15 01:21:15.1342|INFO|GW-1|<GW:1,0> Written XML, bytes:631, msg:<?xml version="1.0" encoding="utf-8"?>
<tbreq bn="1" gw="1" ae="true" ty="pull">
<ird se="A;10" ba="0x35" in="10812" is="60" />
<ird se="A;6" ba="0x35" in="10162" is="60" />
<ird se="A;8" ba="0x35" in="10165" is="60" />
<ird se="A;2" ba="0x8" in="13002" is="60" />
<ird se="A;12" ba="0x51" in="2593" is="60" />
<ird se="A;4" ba="0x8" in="13006" is="60" />
<ird se="A;9" ba="0x35" in="10920" is="60" />
<ird se="A;11" ba="0x35" in="10130" is="60" />
<ird se="A;5" ba="0x35" in="10163" is="60" />
<ird se="A;7" ba="0x35" in="10185" is="60" />
<ird se="A;1" ba="0x8" in="13081" is="60" />
<ird se="A;3" ba="0x8" in="13004" is="60" />
</tbreq>
2023-07-15 01:21:23.6349|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:21:23.6349|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=0
2023-07-15 01:21:23.6383|TRACE|GW-1|<GW:1,0> lenToRead: 854
2023-07-15 01:21:23.6383|TRACE|GW-1|<GW:1,0> telegrType TgrBundleResponse
2023-07-15 01:21:23.6383|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:854 [6/854]
2023-07-15 01:21:23.7149|TRACE|GW-1|<GW:1,0> Got 166 bytes from stream, elapsedMs=79
2023-07-15 01:21:23.7153|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:688 [172/854]
2023-07-15 01:21:23.7948|TRACE|GW-1|<GW:1,0> Got 124 bytes from stream, elapsedMs=159
2023-07-15 01:21:23.7948|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:564 [296/854]
2023-07-15 01:21:23.8747|TRACE|GW-1|<GW:1,0> Got 123 bytes from stream, elapsedMs=239
2023-07-15 01:21:23.8747|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:441 [419/854]
2023-07-15 01:21:23.9549|TRACE|GW-1|<GW:1,0> Got 123 bytes from stream, elapsedMs=319
2023-07-15 01:21:23.9549|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:318 [542/854]
2023-07-15 01:21:24.0345|TRACE|GW-1|<GW:1,0> Got 63 bytes from stream, elapsedMs=399
2023-07-15 01:21:24.0345|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:255 [605/854]
2023-07-15 01:21:24.0747|TRACE|GW-1|<GW:1,0> Got 62 bytes from stream, elapsedMs=439
2023-07-15 01:21:24.0747|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:193 [667/854]
2023-07-15 01:21:24.1144|TRACE|GW-1|<GW:1,0> Got 63 bytes from stream, elapsedMs=479
2023-07-15 01:21:24.1144|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:130 [730/854]
2023-07-15 01:21:24.1544|TRACE|GW-1|<GW:1,0> Got 61 bytes from stream, elapsedMs=519
2023-07-15 01:21:24.1544|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:69 [791/854]
2023-07-15 01:21:24.1947|TRACE|GW-1|<GW:1,0> Got 61 bytes from stream, elapsedMs=559
2023-07-15 01:21:24.1947|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:8 [852/854]
2023-07-15 01:21:24.2339|TRACE|GW-1|<GW:1,0> Got 8 bytes from stream, elapsedMs=598
2023-07-15 01:21:24.2339|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<tbres bn="1" gw="1" st="OK" ts="2023-07-15T01:21:34" emsg="">
<irs se="A;10" ba="0x35" in="10812" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;6" ba="0x35" in="10162" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;8" ba="0x35" in="10165" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;2" ba="0x8" in="13002" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;12" ba="0x51" in="2593" dl="0x8" dh="0x0" st="OK"/>
<irs se="A;4" ba="0x8" in="13006" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;9" ba="0x35" in="10920" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;11" ba="0x35" in="10130" dl="0x1" dh="0x0" st="OK"/>
<irs se="A;5" ba="0x35" in="10163" dl="0x2" dh="0x1" st="OK"/>
<irs se="A;7" ba="0x35" in="10185" dl="0x2C" dh="0x1" st="OK"/>
<irs se="A;1" ba="0x8" in="13081" dl="0x2" dh="0x0" st="OK"/>
<irs se="A;3" ba="0x8" in="13004" dl="0x0" dh="0x0" st="OK"/>
</tbres>
2023-07-15 01:21:24.3712|INFO|GW-1|<GW:1,-1> - UpdateBusconfigStep1
2023-07-15 01:21:24.4728|INFO|GW-1|<GW:1,-1> >>>>>>>>>>>> state:1, count:7{
{Device(-1), visible:True, ba:0x00, dt:GW, di:0 hdt:-1, sv:, sr:, wrsId:, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:190000, ConfigIndex:0, ConfigMask:0}
{Device(-2), visible:True, ba:0x8, dt:HG, di:1 hdt:-1, sv:0x1, sr:0x50, wrsId:0x29, HWSInd:-1, SYSHWSInd:0, IsCombinedDevice:False, DeviceTemplateId:270000, ConfigIndex:3, ConfigMask:1088}
{Device(-3), visible:True, ba:0x35, dt:BM, di:0 hdt:-1, sv:0x2, sr:0x4, wrsId:0x20, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:220000, ConfigIndex:0, ConfigMask:49152}
{Device(-4), visible:True, ba:0x51, dt:MM, di:1 hdt:40000, sv:0x1, sr:0x0, wrsId:0x14, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:40000, ConfigIndex:8, ConfigMask:16}
{Device(-5), visible:True, ba:0x35, dt:DHK, di:0 hdt:270000, sv:, sr:, wrsId:, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:340000, ConfigIndex:0, ConfigMask:0}
{Device(-6), visible:True, ba:0x35, dt:WWSystem, di:0 hdt:270000, sv:, sr:, wrsId:, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:350000, ConfigIndex:0, ConfigMask:0}
{Device(-7), visible:True, ba:0x85, dt:MK, di:1 hdt:40000, sv:, sr:, wrsId:, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:360000, ConfigIndex:0, ConfigMask:0}}
<<<<<<<<<<<<
2023-07-15 01:21:24.4975|INFO|GW-1|<GW:1,-1> - UpdateBusconfigStep2
2023-07-15 01:21:24.4975|INFO|GW-1|<GW:1,0> Written XML, bytes:1018, msg:<?xml version="1.0" encoding="utf-8"?>
<tbreq bn="10" gw="1" ae="true" ty="pull">
<ird se="A;17" ba="0x8" in="12435" is="60" />
<ird se="A;31" ba="0x85" in="9024" is="60" />
<ird se="A;33" ba="0x85" in="9028" is="60" />
<ird se="A;26" ba="0x35" in="9007" is="60" />
<ird se="A;23" ba="0x35" in="5266" is="60" />
<ird se="A;30" ba="0x85" in="10155" is="60" />
<ird se="C;16;10879;10880" ba="0x8" in="10879" is="60" />
<ird se="C;16;10879;10880" ba="0x8" in="10880" is="60" />
<ird se="A;21" ba="0x35" in="10180" is="60" />
<ird se="A;24" ba="0x35" in="10155" is="60" />
<ird se="A;15" ba="0x8" in="10878" is="60" />
<ird se="A;22" ba="0x51" in="410" is="60" />
<ird se="A;25" ba="0x35" in="9024" is="60" />
<ird se="A;28" ba="0x35" in="10134" is="60" />
<ird se="A;14" ba="0x8" in="10877" is="60" />
<ird se="A;13" ba="0x8" in="410" is="60" />
<ird se="A;32" ba="0x85" in="9007" is="60" />
<ird se="A;27" ba="0x35" in="9028" is="60" />
<ird se="A;18" ba="0x8" in="10040" is="60" />
<ird se="A;20" ba="0x35" in="618" is="60" />
</tbreq>
2023-07-15 01:21:26.9547|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:21:26.9547|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=0
2023-07-15 01:21:26.9547|TRACE|GW-1|<GW:1,0> lenToRead: 1259
2023-07-15 01:21:26.9547|TRACE|GW-1|<GW:1,0> telegrType TgrBundleResponse
2023-07-15 01:21:26.9547|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1259 [6/1259]
2023-07-15 01:21:27.0351|TRACE|GW-1|<GW:1,0> Got 166 bytes from stream, elapsedMs=80
2023-07-15 01:21:27.0351|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1093 [172/1259]
2023-07-15 01:21:27.1204|TRACE|GW-1|<GW:1,0> Got 44 bytes from stream, elapsedMs=165
2023-07-15 01:21:27.1204|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1049 [216/1259]
2023-07-15 01:21:27.1546|TRACE|GW-1|<GW:1,0> Got 44 bytes from stream, elapsedMs=199
2023-07-15 01:21:27.1546|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1005 [260/1259]
2023-07-15 01:21:27.1945|TRACE|GW-1|<GW:1,0> Got 44 bytes from stream, elapsedMs=239
2023-07-15 01:21:27.1945|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:961 [304/1259]
2023-07-15 01:21:27.2344|TRACE|GW-1|<GW:1,0> Got 62 bytes from stream, elapsedMs=279
2023-07-15 01:21:27.2344|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:899 [366/1259]
2023-07-15 01:21:27.2744|TRACE|GW-1|<GW:1,0> Got 63 bytes from stream, elapsedMs=319
2023-07-15 01:21:27.2744|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:836 [429/1259]
2023-07-15 01:21:27.3147|TRACE|GW-1|<GW:1,0> Got 76 bytes from stream, elapsedMs=359
2023-07-15 01:21:27.3147|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:760 [505/1259]
2023-07-15 01:21:27.3544|TRACE|GW-1|<GW:1,0> Got 76 bytes from stream, elapsedMs=399
2023-07-15 01:21:27.3544|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:684 [581/1259]
2023-07-15 01:21:27.3947|TRACE|GW-1|<GW:1,0> Got 63 bytes from stream, elapsedMs=439
2023-07-15 01:21:27.3947|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:621 [644/1259]
2023-07-15 01:21:27.4344|TRACE|GW-1|<GW:1,0> Got 63 bytes from stream, elapsedMs=479
2023-07-15 01:21:27.4344|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:558 [707/1259]
2023-07-15 01:21:27.4745|TRACE|GW-1|<GW:1,0> Got 64 bytes from stream, elapsedMs=519
2023-07-15 01:21:27.4745|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:494 [771/1259]
2023-07-15 01:21:27.5147|TRACE|GW-1|<GW:1,0> Got 43 bytes from stream, elapsedMs=559
2023-07-15 01:21:27.5147|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:451 [814/1259]
2023-07-15 01:21:27.5548|TRACE|GW-1|<GW:1,0> Got 107 bytes from stream, elapsedMs=599
2023-07-15 01:21:27.5548|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:344 [921/1259]
2023-07-15 01:21:27.6348|TRACE|GW-1|<GW:1,0> Got 125 bytes from stream, elapsedMs=679
2023-07-15 01:21:27.6348|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:219 [1046/1259]
2023-07-15 01:21:27.7148|TRACE|GW-1|<GW:1,0> Got 88 bytes from stream, elapsedMs=759
2023-07-15 01:21:27.7148|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:131 [1134/1259]
2023-07-15 01:21:27.7948|TRACE|GW-1|<GW:1,0> Got 123 bytes from stream, elapsedMs=839
2023-07-15 01:21:27.7948|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:8 [1257/1259]
2023-07-15 01:21:27.8782|TRACE|GW-1|<GW:1,0> Got 8 bytes from stream, elapsedMs=923
2023-07-15 01:21:27.8782|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<tbres bn="10" gw="1" st="OK" ts="2023-07-15T01:21:38" emsg="">
<irs se="A;17" ba="0x8" in="12435" dl="0x1" dh="0x0" st="OK"/>
<irs se="A;31" ba="0x85" in="9024" st="ER"/>
<irs se="A;33" ba="0x85" in="9028" st="ER"/>
<irs se="A;26" ba="0x35" in="9007" st="ER"/>
<irs se="A;23" ba="0x35" in="5266" dl="0x1" dh="0x0" st="OK"/>
<irs se="A;30" ba="0x85" in="10155" dl="0x8" dh="0x0" st="OK"/>
<irs se="C;16;10879;10880" ba="0x8" in="10879" dl="0xFF" dh="0xFF" st="OK"/>
<irs se="C;16;10879;10880" ba="0x8" in="10880" dl="0xFF" dh="0xFF" st="OK"/>
<irs se="A;21" ba="0x35" in="10180" dl="0x8" dh="0x0" st="OK"/>
<irs se="A;24" ba="0x35" in="10155" dl="0x8" dh="0x0" st="OK"/>
<irs se="A;15" ba="0x8" in="10878" dl="0xFF" dh="0xFF" st="OK"/>
<irs se="A;22" ba="0x51" in="410" st="ER"/>
<irs se="A;25" ba="0x35" in="9024" st="ER"/>
<irs se="A;28" ba="0x35" in="10134" dl="0x9" dh="0x0" st="OK"/>
<irs se="A;14" ba="0x8" in="10877" dl="0xFF" dh="0xFF" st="OK"/>
<irs se="A;13" ba="0x8" in="410" dl="0x96" dh="0x0" st="OK"/>
<irs se="A;32" ba="0x85" in="9007" st="ER"/>
<irs se="A;27" ba="0x35" in="9028" st="ER"/>
<irs se="A;18" ba="0x8" in="10040" dl="0xA" dh="0x0" st="OK"/>
<irs se="A;20" ba="0x35" in="618" dl="0x2" dh="0x0" st="OK"/>
</tbres>
2023-07-15 01:21:27.8921|WARN|GW-1|<GW:1,-1> - Response state:ER !!! entry:Resp -> Seq:A;31, Ba:0x85, TelegrNr:9024, State:ER, set value to null
2023-07-15 01:21:27.8921|WARN|GW-1|<GW:1,-1> - Response state:ER !!! entry:Resp -> Seq:A;33, Ba:0x85, TelegrNr:9028, State:ER, set value to null
2023-07-15 01:21:27.8921|WARN|GW-1|<GW:1,-1> - Response state:ER !!! entry:Resp -> Seq:A;26, Ba:0x35, TelegrNr:9007, State:ER, set value to null
2023-07-15 01:21:27.9099|WARN|GW-1|<GW:1,-1> - Response state:ER !!! entry:Resp -> Seq:A;22, Ba:0x51, TelegrNr:410, State:ER, set value to null
2023-07-15 01:21:27.9099|WARN|GW-1|<GW:1,-1> - Response state:ER !!! entry:Resp -> Seq:A;25, Ba:0x35, TelegrNr:9024, State:ER, set value to null
2023-07-15 01:21:27.9099|WARN|GW-1|<GW:1,-1> - Response state:ER !!! entry:Resp -> Seq:A;32, Ba:0x85, TelegrNr:9007, State:ER, set value to null
2023-07-15 01:21:27.9099|WARN|GW-1|<GW:1,-1> - Response state:ER !!! entry:Resp -> Seq:A;27, Ba:0x35, TelegrNr:9028, State:ER, set value to null
2023-07-15 01:21:27.9238|INFO|GW-1|<GW:1,0> Written XML, bytes:184, msg:<?xml version="1.0" encoding="utf-8"?>
<tbreq bn="11" gw="1" ae="true" ty="pull">
<ird se="A;29" ba="0x85" in="5266" is="60" />
<ird se="A;19" ba="0x8" in="10888" is="60" />
</tbreq>
2023-07-15 01:21:29.1259|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:21:29.1259|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=0
2023-07-15 01:21:29.1259|TRACE|GW-1|<GW:1,0> lenToRead: 219
2023-07-15 01:21:29.1259|TRACE|GW-1|<GW:1,0> telegrType TgrBundleResponse
2023-07-15 01:21:29.1259|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:219 [6/219]
2023-07-15 01:21:29.2059|TRACE|GW-1|<GW:1,0> Got 148 bytes from stream, elapsedMs=79
2023-07-15 01:21:29.2059|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:71 [154/219]
2023-07-15 01:21:29.2857|TRACE|GW-1|<GW:1,0> Got 71 bytes from stream, elapsedMs=159
2023-07-15 01:21:29.2857|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<tbres bn="11" gw="1" st="OK" ts="2023-07-15T01:21:40" emsg="">
<irs se="A;29" ba="0x85" in="5266" st="ER"/>
<irs se="A;19" ba="0x8" in="10888" dl="0x0" dh="0x80" st="OK"/>
</tbres>
2023-07-15 01:21:29.2857|WARN|GW-1|<GW:1,-1> - Response state:ER !!! entry:Resp -> Seq:A;29, Ba:0x85, TelegrNr:5266, State:ER, set value to null
2023-07-15 01:21:29.2934|ERROR|GW-1|<GW:1,-1> - Missing device answer - ParameterId:36013200001, state:Error
2023-07-15 01:21:29.2934|ERROR|GW-1|<GW:1,-1> - Missing device answer - ParameterId:36014500001, state:Error
2023-07-15 01:21:29.2934|ERROR|GW-1|<GW:1,-1> - Missing device answer - ParameterId:34005000000, state:Error
2023-07-15 01:21:29.2934|ERROR|GW-1|<GW:1,-1> - Missing device answer - ParameterId:4009300001, state:Error
2023-07-15 01:21:29.2934|ERROR|GW-1|<GW:1,-1> - Missing device answer - ParameterId:34004500000, state:Error
2023-07-15 01:21:29.2934|ERROR|GW-1|<GW:1,-1> - Missing device answer - ParameterId:36013600001, state:Error
2023-07-15 01:21:29.2934|ERROR|GW-1|<GW:1,-1> - Missing device answer - ParameterId:34005500000, state:Error
2023-07-15 01:21:29.2934|ERROR|GW-1|<GW:1,-1> - Missing device answer - ParameterId:36006600001, state:Error
2023-07-15 01:21:29.3133|INFO|GW-1|<GW:1,-1> - FinishBusconfig
2023-07-15 01:21:29.3821|DEBUG|GW-1|<GW:1,-1> BundleType:push, deviceVisibleReadAddresses:0x8,0x35,0x51
2023-07-15 01:21:29.3821|INFO|GW-1|<GW:1,0> Written XML, bytes:127, msg:<?xml version="1.0" encoding="utf-8"?>
<tbreq bn="3" gw="1" ae="false" ty="push">
<erd se="3" li="0x8,0x35,0x51" />
</tbreq>
2023-07-15 01:21:29.4590|DEBUG|GW-1|<GW:1,-1> Detect SmartHome-System...
2023-07-15 01:21:29.4590|DEBUG|GW-1|<GW:1,-1> SmartHome = 0
2023-07-15 01:21:29.6998|INFO|GW-1|<GW:1,0> Written XML, bytes:1047, msg:<?xml version="1.0" encoding="utf-8"?>
<tbreq bn="1000" gw="1" ae="true" ty="push">
<ird se="A;186" ba="0x35" in="10148" is="60" />
<ird se="C;62;12411;12410" ba="0x8" in="12411" is="60" />
<ird se="C;62;12411;12410" ba="0x8" in="12410" is="60" />
<ird se="A;191" ba="0x35" in="5250" is="60" />
<ird se="A;28" ba="0x35" in="10134" is="60" />
<ird se="A;24" ba="0x35" in="10155" is="60" />
<ird se="B;188;181" ba="0x35" in="10161" is="60" />
<ird se="A;319" ba="0x85" in="10159" is="60" />
<ird se="B;218;217" ba="0x51" in="82" is="60" />
<ird se="A;51" ba="0x8" in="12" is="60" />
<ird se="A;290" ba="0x85" in="10100" is="60" />
<ird se="A;68" ba="0x8" in="14" is="60" />
<ird se="B;248;249" ba="0x35" in="10100" is="60" />
<ird se="A;293" ba="0x85" in="10179" is="60" />
<ird se="A;183" ba="0x35" in="10150" is="60" />
<ird se="A;56" ba="0x8" in="6105" is="60" />
<ird se="A;55" ba="0x8" in="22" is="60" />
<ird se="B;245;244" ba="0x35" in="10165" is="60" />
<ird se="B;333;334" ba="0x35" in="19" is="60" />
<ird se="A;59" ba="0x8" in="12389" is="60" />
</tbreq>
2023-07-15 01:21:41.4549|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:21:41.4549|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=0
2023-07-15 01:21:41.4549|TRACE|GW-1|<GW:1,0> lenToRead: 1414
2023-07-15 01:21:41.4549|TRACE|GW-1|<GW:1,0> telegrType TgrBundleResponse
2023-07-15 01:21:41.4549|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1414 [6/1414]
2023-07-15 01:21:41.5351|TRACE|GW-1|<GW:1,0> Got 171 bytes from stream, elapsedMs=79
2023-07-15 01:21:41.5351|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1243 [177/1414]
2023-07-15 01:21:41.6151|TRACE|GW-1|<GW:1,0> Got 149 bytes from stream, elapsedMs=159
2023-07-15 01:21:41.6151|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1094 [326/1414]
2023-07-15 01:21:41.6951|TRACE|GW-1|<GW:1,0> Got 128 bytes from stream, elapsedMs=239
2023-07-15 01:21:41.6951|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:966 [454/1414]
2023-07-15 01:21:41.7750|TRACE|GW-1|<GW:1,0> Got 132 bytes from stream, elapsedMs=319
2023-07-15 01:21:41.7750|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:834 [586/1414]
2023-07-15 01:21:41.8551|TRACE|GW-1|<GW:1,0> Got 129 bytes from stream, elapsedMs=399
2023-07-15 01:21:41.8551|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:705 [715/1414]
2023-07-15 01:21:41.9351|TRACE|GW-1|<GW:1,0> Got 124 bytes from stream, elapsedMs=479
2023-07-15 01:21:41.9351|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:581 [839/1414]
2023-07-15 01:21:42.0149|TRACE|GW-1|<GW:1,0> Got 128 bytes from stream, elapsedMs=559
2023-07-15 01:21:42.0149|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:453 [967/1414]
2023-07-15 01:21:42.0948|TRACE|GW-1|<GW:1,0> Got 129 bytes from stream, elapsedMs=639
2023-07-15 01:21:42.0948|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:324 [1096/1414]
2023-07-15 01:21:42.1754|TRACE|GW-1|<GW:1,0> Got 121 bytes from stream, elapsedMs=720
2023-07-15 01:21:42.1754|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:203 [1217/1414]
2023-07-15 01:21:42.2548|TRACE|GW-1|<GW:1,0> Got 133 bytes from stream, elapsedMs=799
2023-07-15 01:21:42.2548|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:70 [1350/1414]
2023-07-15 01:21:42.3349|TRACE|GW-1|<GW:1,0> Got 70 bytes from stream, elapsedMs=879
2023-07-15 01:21:42.3349|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<tbres bn="1000" gw="1" st="OK" ts="2023-07-15T01:21:52" emsg="">
<irs se="A;186" ba="0x35" in="10148" dl="0x14" dh="0x1" st="OK"/>
<irs se="C;62;12411;12410" ba="0x8" in="12411" dl="0x0" dh="0x0" st="OK"/>
<irs se="C;62;12411;12410" ba="0x8" in="12410" dl="0xCE" dh="0xB" st="OK"/>
<irs se="A;191" ba="0x35" in="5250" dl="0xEB" dh="0x2E" st="OK"/>
<irs se="A;28" ba="0x35" in="10134" dl="0x9" dh="0x0" st="OK"/>
<irs se="A;24" ba="0x35" in="10155" dl="0x8" dh="0x0" st="OK"/>
<irs se="B;188;181" ba="0x35" in="10161" dl="0x15" dh="0x1" st="OK"/>
<irs se="A;319" ba="0x85" in="10159" dl="0x1" dh="0x0" st="OK"/>
<irs se="B;218;217" ba="0x51" in="82" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;51" ba="0x8" in="12" dl="0xE2" dh="0x0" st="OK"/>
<irs se="A;290" ba="0x85" in="10100" dl="0x1" dh="0x0" st="OK"/>
<irs se="A;68" ba="0x8" in="14" dl="0x2B" dh="0x2" st="OK"/>
<irs se="B;248;249" ba="0x35" in="10100" dl="0x1" dh="0x0" st="OK"/>
<irs se="A;293" ba="0x85" in="10179" dl="0xF6" dh="0x0" st="OK"/>
<irs se="A;183" ba="0x35" in="10150" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;56" ba="0x8" in="6105" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;55" ba="0x8" in="22" dl="0xE7" dh="0x0" st="OK"/>
<irs se="B;245;244" ba="0x35" in="10165" dl="0x0" dh="0x0" st="OK"/>
<irs se="B;333;334" ba="0x35" in="19" dl="0x8" dh="0x2" st="OK"/>
<irs se="A;59" ba="0x8" in="12389" dl="0x0" dh="0x0" st="OK"/>
</tbres>
2023-07-15 01:21:42.4263|INFO|GW-1|<GW:1,0> Written XML, bytes:1082, msg:<?xml version="1.0" encoding="utf-8"?>
<tbreq bn="1001" gw="1" ae="true" ty="push">
<ird se="A;57" ba="0x8" in="10010" is="60" />
<ird se="B;34;36;35;37;38" ba="0x8" in="12409" is="60" />
<ird se="A;23" ba="0x35" in="5266" is="60" />
<ird se="A;296" ba="0x85" in="10156" is="60" />
<ird se="A;50" ba="0x8" in="12392" is="60" />
<ird se="A;178" ba="0x35" in="10152" is="60" />
<ird se="B;291;297" ba="0x85" in="10109" is="60" />
<ird se="B;246;252;257" ba="0x35" in="10109" is="60" />
<ird se="A;279" ba="0x35" in="10159" is="60" />
<ird se="A;180" ba="0x35" in="10154" is="60" />
<ird se="B;336;335" ba="0x35" in="10101" is="60" />
<ird se="A;195" ba="0x35" in="10117" is="60" />
<ird se="B;182;189" ba="0x35" in="10160" is="60" />
<ird se="A;294" ba="0x85" in="10119" is="60" />
<ird se="A;65" ba="0x8" in="10814" is="60" />
<ird se="A;250" ba="0x35" in="10179" is="60" />
<ird se="A;185" ba="0x35" in="10147" is="60" />
<ird se="B;247;253" ba="0x35" in="10164" is="60" />
<ird se="C;61;12400;12399" ba="0x8" in="12400" is="60" />
<ird se="C;61;12400;12399" ba="0x8" in="12399" is="60" />
</tbreq>
2023-07-15 01:21:55.5179|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:21:55.5179|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=0
2023-07-15 01:21:55.5179|TRACE|GW-1|<GW:1,0> lenToRead: 1456
2023-07-15 01:21:55.5179|TRACE|GW-1|<GW:1,0> telegrType TgrBundleResponse
2023-07-15 01:21:55.5179|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1456 [6/1456]
2023-07-15 01:21:55.5980|TRACE|GW-1|<GW:1,0> Got 169 bytes from stream, elapsedMs=79
2023-07-15 01:21:55.5980|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1287 [175/1456]
2023-07-15 01:21:55.6780|TRACE|GW-1|<GW:1,0> Got 136 bytes from stream, elapsedMs=159
2023-07-15 01:21:55.6780|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1151 [311/1456]
2023-07-15 01:21:55.7582|TRACE|GW-1|<GW:1,0> Got 126 bytes from stream, elapsedMs=240
2023-07-15 01:21:55.7582|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1025 [437/1456]
2023-07-15 01:21:55.8380|TRACE|GW-1|<GW:1,0> Got 135 bytes from stream, elapsedMs=319
2023-07-15 01:21:55.8380|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:890 [572/1456]
2023-07-15 01:21:55.9181|TRACE|GW-1|<GW:1,0> Got 137 bytes from stream, elapsedMs=399
2023-07-15 01:21:55.9181|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:753 [709/1456]
2023-07-15 01:21:56.0010|TRACE|GW-1|<GW:1,0> Got 134 bytes from stream, elapsedMs=482
2023-07-15 01:21:56.0010|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:619 [843/1456]
2023-07-15 01:21:56.0780|TRACE|GW-1|<GW:1,0> Got 134 bytes from stream, elapsedMs=559
2023-07-15 01:21:56.0780|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:485 [977/1456]
2023-07-15 01:21:56.1579|TRACE|GW-1|<GW:1,0> Got 127 bytes from stream, elapsedMs=639
2023-07-15 01:21:56.1579|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:358 [1104/1456]
2023-07-15 01:21:56.2379|TRACE|GW-1|<GW:1,0> Got 131 bytes from stream, elapsedMs=719
2023-07-15 01:21:56.2379|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:227 [1235/1456]
2023-07-15 01:21:56.3182|TRACE|GW-1|<GW:1,0> Got 143 bytes from stream, elapsedMs=800
2023-07-15 01:21:56.3182|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:84 [1378/1456]
2023-07-15 01:21:56.3981|TRACE|GW-1|<GW:1,0> Got 84 bytes from stream, elapsedMs=879
2023-07-15 01:21:56.3981|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<tbres bn="1001" gw="1" st="OK" ts="2023-07-15T01:22:06" emsg="">
<irs se="A;57" ba="0x8" in="10010" dl="0xC9" dh="0x0" st="OK"/>
<irs se="B;34;36;35;37;38" ba="0x8" in="12409" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;23" ba="0x35" in="5266" dl="0x1" dh="0x0" st="OK"/>
<irs se="A;296" ba="0x85" in="10156" dl="0x5" dh="0x0" st="OK"/>
<irs se="A;50" ba="0x8" in="12392" dl="0x3" dh="0x0" st="OK"/>
<irs se="A;178" ba="0x35" in="10152" dl="0xCE" dh="0x2E" st="OK"/>
<irs se="B;291;297" ba="0x85" in="10109" dl="0xC8" dh="0x0" st="OK"/>
<irs se="B;246;252;257" ba="0x35" in="10109" dl="0xC8" dh="0x0" st="OK"/>
<irs se="A;279" ba="0x35" in="10159" dl="0x1" dh="0x0" st="OK"/>
<irs se="A;180" ba="0x35" in="10154" dl="0xCE" dh="0x2E" st="OK"/>
<irs se="B;336;335" ba="0x35" in="10101" dl="0x1" dh="0x0" st="OK"/>
<irs se="A;195" ba="0x35" in="10117" dl="0x0" dh="0x0" st="OK"/>
<irs se="B;182;189" ba="0x35" in="10160" dl="0xCE" dh="0x2E" st="OK"/>
<irs se="A;294" ba="0x85" in="10119" dl="0x11" dh="0x1" st="OK"/>
<irs se="A;65" ba="0x8" in="10814" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;250" ba="0x35" in="10179" dl="0xF6" dh="0x0" st="OK"/>
<irs se="A;185" ba="0x35" in="10147" dl="0xCE" dh="0x2E" st="OK"/>
<irs se="B;247;253" ba="0x35" in="10164" dl="0x0" dh="0x80" st="OK"/>
<irs se="C;61;12400;12399" ba="0x8" in="12400" dl="0x0" dh="0x0" st="OK"/>
<irs se="C;61;12400;12399" ba="0x8" in="12399" dl="0xFD" dh="0x25" st="OK"/>
</tbres>
2023-07-15 01:21:56.4125|INFO|GW-1|<GW:1,0> Written XML, bytes:1032, msg:<?xml version="1.0" encoding="utf-8"?>
<tbreq bn="1002" gw="1" ae="true" ty="push">
<ird se="A;360" ba="0x35" in="5249" is="60" />
<ird se="A;187" ba="0x35" in="10149" is="60" />
<ird se="C;63;12413;12412" ba="0x8" in="12413" is="60" />
<ird se="C;63;12413;12412" ba="0x8" in="12412" is="60" />
<ird se="A;256" ba="0x35" in="10156" is="60" />
<ird se="A;53" ba="0x8" in="2" is="60" />
<ird se="A;359" ba="0x35" in="10158" is="60" />
<ird se="C;219;86;87" ba="0x51" in="86" is="60" />
<ird se="C;219;86;87" ba="0x51" in="87" is="60" />
<ird se="A;52" ba="0x8" in="13" is="60" />
<ird se="A;292" ba="0x85" in="10164" is="60" />
<ird se="A;64" ba="0x8" in="554" is="60" />
<ird se="A;69" ba="0x8" in="19" is="60" />
<ird se="A;82" ba="0x8" in="12385" is="60" />
<ird se="A;254" ba="0x35" in="10119" is="60" />
<ird se="A;190" ba="0x35" in="10145" is="60" />
<ird se="A;54" ba="0x8" in="10007" is="60" />
<ird se="A;289" ba="0x85" in="10165" is="60" />
<ird se="A;58" ba="0x8" in="367" is="60" />
<ird se="A;30" ba="0x85" in="10155" is="60" />
</tbreq>
2023-07-15 01:21:56.7380|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:21:56.7380|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=0
2023-07-15 01:21:56.7380|TRACE|GW-1|<GW:1,0> lenToRead: 86
2023-07-15 01:21:56.7380|TRACE|GW-1|<GW:1,0> telegrType TgrBundleResponse
2023-07-15 01:21:56.7380|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:86 [6/86]
2023-07-15 01:21:56.7827|TRACE|GW-1|<GW:1,0> Got 86 bytes from stream, elapsedMs=44
2023-07-15 01:21:56.7827|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<tbres bn="3" gw="1" st="OK" emsg="">
</tbres>
2023-07-15 01:22:04.2101|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:22:04.2101|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=0
2023-07-15 01:22:04.2101|TRACE|GW-1|<GW:1,0> lenToRead: 1398
2023-07-15 01:22:04.2162|TRACE|GW-1|<GW:1,0> telegrType TgrBundleResponse
2023-07-15 01:22:04.2162|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1398 [6/1398]
2023-07-15 01:22:04.2901|TRACE|GW-1|<GW:1,0> Got 170 bytes from stream, elapsedMs=79
2023-07-15 01:22:04.2901|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1228 [176/1398]
2023-07-15 01:22:04.3700|TRACE|GW-1|<GW:1,0> Got 140 bytes from stream, elapsedMs=159
2023-07-15 01:22:04.3700|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:1088 [316/1398]
2023-07-15 01:22:04.4504|TRACE|GW-1|<GW:1,0> Got 138 bytes from stream, elapsedMs=239
2023-07-15 01:22:04.4504|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:950 [454/1398]
2023-07-15 01:22:04.5300|TRACE|GW-1|<GW:1,0> Got 123 bytes from stream, elapsedMs=319
2023-07-15 01:22:04.5300|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:827 [577/1398]
2023-07-15 01:22:04.6100|TRACE|GW-1|<GW:1,0> Got 67 bytes from stream, elapsedMs=399
2023-07-15 01:22:04.6100|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:760 [644/1398]
2023-07-15 01:22:04.6498|TRACE|GW-1|<GW:1,0> Got 67 bytes from stream, elapsedMs=439
2023-07-15 01:22:04.6498|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:693 [711/1398]
2023-07-15 01:22:04.6900|TRACE|GW-1|<GW:1,0> Got 60 bytes from stream, elapsedMs=479
2023-07-15 01:22:04.6900|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:633 [771/1398]
2023-07-15 01:22:04.7297|TRACE|GW-1|<GW:1,0> Got 65 bytes from stream, elapsedMs=519
2023-07-15 01:22:04.7297|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:568 [836/1398]
2023-07-15 01:22:04.7699|TRACE|GW-1|<GW:1,0> Got 61 bytes from stream, elapsedMs=559
2023-07-15 01:22:04.7699|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:507 [897/1398]
2023-07-15 01:22:04.8098|TRACE|GW-1|<GW:1,0> Got 60 bytes from stream, elapsedMs=599
2023-07-15 01:22:04.8098|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:447 [957/1398]
2023-07-15 01:22:04.8493|TRACE|GW-1|<GW:1,0> Got 62 bytes from stream, elapsedMs=638
2023-07-15 01:22:04.8493|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:385 [1019/1398]
2023-07-15 01:22:04.8897|TRACE|GW-1|<GW:1,0> Got 64 bytes from stream, elapsedMs=679
2023-07-15 01:22:04.8897|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:321 [1083/1398]
2023-07-15 01:22:04.9324|TRACE|GW-1|<GW:1,0> Got 64 bytes from stream, elapsedMs=721
2023-07-15 01:22:04.9324|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:257 [1147/1398]
2023-07-15 01:22:04.9748|TRACE|GW-1|<GW:1,0> Got 126 bytes from stream, elapsedMs=764
2023-07-15 01:22:04.9748|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:131 [1273/1398]
2023-07-15 01:22:05.0511|TRACE|GW-1|<GW:1,0> Got 123 bytes from stream, elapsedMs=840
2023-07-15 01:22:05.0511|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:8 [1396/1398]
2023-07-15 01:22:05.1347|TRACE|GW-1|<GW:1,0> Got 8 bytes from stream, elapsedMs=924
2023-07-15 01:22:05.1347|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<tbres bn="1002" gw="1" st="OK" ts="2023-07-15T01:22:16" emsg="">
<irs se="A;360" ba="0x35" in="5249" dl="0x0" dh="0x80" st="OK"/>
<irs se="A;187" ba="0x35" in="10149" dl="0xCE" dh="0x2E" st="OK"/>
<irs se="C;63;12413;12412" ba="0x8" in="12413" dl="0x0" dh="0x0" st="OK"/>
<irs se="C;63;12413;12412" ba="0x8" in="12412" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;256" ba="0x35" in="10156" dl="0x5" dh="0x0" st="OK"/>
<irs se="A;53" ba="0x8" in="2" dl="0x32" dh="0x0" st="OK"/>
<irs se="A;359" ba="0x35" in="10158" dl="0x2" dh="0x0" st="OK"/>
<irs se="C;219;86;87" ba="0x51" in="86" dl="0x0" dh="0x0" st="OK"/>
<irs se="C;219;86;87" ba="0x51" in="87" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;52" ba="0x8" in="13" dl="0x60" dh="0x1" st="OK"/>
<irs se="A;292" ba="0x85" in="10164" dl="0x0" dh="0x80" st="OK"/>
<irs se="A;64" ba="0x8" in="554" dl="0x81" dh="0xB" st="OK"/>
<irs se="A;69" ba="0x8" in="19" dl="0x64" dh="0x0" st="OK"/>
<irs se="A;82" ba="0x8" in="12385" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;254" ba="0x35" in="10119" dl="0x1" dh="0x1" st="OK"/>
<irs se="A;190" ba="0x35" in="10145" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;54" ba="0x8" in="10007" dl="0x1" dh="0x1" st="OK"/>
<irs se="A;289" ba="0x85" in="10165" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;58" ba="0x8" in="367" dl="0x0" dh="0x0" st="OK"/>
<irs se="A;30" ba="0x85" in="10155" dl="0x8" dh="0x0" st="OK"/>
</tbres>
2023-07-15 01:22:05.1668|INFO|GW-1|<GW:1,0> Written XML, bytes:371, msg:<?xml version="1.0" encoding="utf-8"?>
<tbreq bn="1003" gw="1" ae="true" ty="push">
<ird se="A;221" ba="0x51" in="15" is="60" />
<ird se="A;49" ba="0x8" in="12391" is="60" />
<ird se="A;177" ba="0x35" in="10151" is="60" />
<ird se="A;179" ba="0x35" in="10153" is="60" />
<ird se="A;184" ba="0x35" in="10146" is="60" />
<ird se="A;60" ba="0x8" in="12398" is="60" />
</tbreq>
2023-07-15 01:22:06.6820|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:22:06.6820|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=0
2023-07-15 01:22:06.6860|TRACE|GW-1|<GW:1,0> lenToRead: 495
2023-07-15 01:22:06.6860|TRACE|GW-1|<GW:1,0> telegrType TgrBundleResponse
2023-07-15 01:22:06.6860|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:495 [6/495]
2023-07-15 01:22:06.7618|TRACE|GW-1|<GW:1,0> Got 106 bytes from stream, elapsedMs=79
2023-07-15 01:22:06.7618|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:389 [112/495]
2023-07-15 01:22:06.8018|TRACE|GW-1|<GW:1,0> Got 62 bytes from stream, elapsedMs=119
2023-07-15 01:22:06.8018|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:327 [174/495]
2023-07-15 01:22:06.8424|TRACE|GW-1|<GW:1,0> Got 127 bytes from stream, elapsedMs=160
2023-07-15 01:22:06.8424|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:200 [301/495]
2023-07-15 01:22:06.9221|TRACE|GW-1|<GW:1,0> Got 130 bytes from stream, elapsedMs=239
2023-07-15 01:22:06.9438|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:70 [431/495]
2023-07-15 01:22:07.0022|TRACE|GW-1|<GW:1,0> Got 70 bytes from stream, elapsedMs=319
2023-07-15 01:22:07.0022|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<tbres bn="1003" gw="1" st="OK" ts="2023-07-15T01:22:18" emsg="">
<irs se="A;221" ba="0x51" in="15" dl="0x11" dh="0x1" st="OK"/>
<irs se="A;49" ba="0x8" in="12391" dl="0xF" dh="0x0" st="OK"/>
<irs se="A;177" ba="0x35" in="10151" dl="0x15" dh="0x4" st="OK"/>
<irs se="A;179" ba="0x35" in="10153" dl="0x15" dh="0x1" st="OK"/>
<irs se="A;184" ba="0x35" in="10146" dl="0x15" dh="0x4" st="OK"/>
<irs se="A;60" ba="0x8" in="12398" dl="0x0" dh="0x0" st="OK"/>
</tbres>
2023-07-15 01:22:07.1339|INFO|GW-1|<GW:1,0> Written XML, bytes:139, msg:<?xml version="1.0" encoding="utf-8"?>
<tbreq bn="700" gw="1" ae="true" ty="push">
<ird se="A;20" ba="0x35" in="618" is="60" />
</tbreq>
2023-07-15 01:22:07.4314|TRACE|GW-1|<GW:1,0> ReadTelegramAsync !!
2023-07-15 01:22:07.4314|TRACE|GW-1|<GW:1,0> Got 6 bytes from stream, elapsedMs=0
2023-07-15 01:22:07.4376|TRACE|GW-1|<GW:1,0> lenToRead: 174
2023-07-15 01:22:07.4376|TRACE|GW-1|<GW:1,0> telegrType TgrBundleResponse
2023-07-15 01:22:07.4376|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:174 [6/174]
2023-07-15 01:22:07.5112|TRACE|GW-1|<GW:1,0> Got 166 bytes from stream, elapsedMs=79
2023-07-15 01:22:07.5112|TRACE|GW-1|<GW:1,0> Try to complete msg, bytesMissing:8 [172/174]
2023-07-15 01:22:07.5957|TRACE|GW-1|<GW:1,0> Got 8 bytes from stream, elapsedMs=164
2023-07-15 01:22:07.5957|INFO|GW-1|<GW:1,0> Got XML msg:???<?xml version="1.0" encoding="utf-8"?>
<tbres bn="700" gw="1" st="OK" ts="2023-07-15T01:22:19" emsg="">
<irs se="A;20" ba="0x35" in="618" dl="0x2" dh="0x0" st="OK"/>
</tbres>
2023-07-15 01:22:07.6118|INFO|GW-1|<GW:1,-1> - FinishBusconfig, gw is online now
2023-07-15 01:22:07.6118|INFO|GW-1|<GW:1,-1> >>>>>>>>>>>> state:1, count:7{
{Device(3), visible:True, ba:0x00, dt:GW, di:0 hdt:-1, sv:, sr:, wrsId:, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:190000, ConfigIndex:0, ConfigMask:0}
{Device(4), visible:True, ba:0x8, dt:HG, di:1 hdt:-1, sv:0x1, sr:0x50, wrsId:0x29, HWSInd:-1, SYSHWSInd:0, IsCombinedDevice:False, DeviceTemplateId:270000, ConfigIndex:3, ConfigMask:1088}
{Device(5), visible:True, ba:0x35, dt:BM, di:0 hdt:-1, sv:0x2, sr:0x4, wrsId:0x20, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:220000, ConfigIndex:0, ConfigMask:49152}
{Device(6), visible:True, ba:0x51, dt:MM, di:1 hdt:40000, sv:0x1, sr:0x0, wrsId:0x14, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:40000, ConfigIndex:8, ConfigMask:16}
{Device(7), visible:True, ba:0x35, dt:DHK, di:0 hdt:270000, sv:, sr:, wrsId:, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:340000, ConfigIndex:0, ConfigMask:0}
{Device(8), visible:True, ba:0x35, dt:WWSystem, di:0 hdt:270000, sv:, sr:, wrsId:, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:350000, ConfigIndex:0, ConfigMask:0}
{Device(9), visible:True, ba:0x85, dt:MK, di:1 hdt:40000, sv:, sr:, wrsId:, HWSInd:-1, SYSHWSInd:-1, IsCombinedDevice:False, DeviceTemplateId:360000, ConfigIndex:0, ConfigMask:0}}
<<<<<<<<<<<<
Where can I find the debug log that you would like to see?
zivillian commented
Where can I find the debug log that you would like to see?
just add an environment variable ISM7_DEBUG=true
IgitBuh commented
Done. Is this debug info you would like to see?
> <?xml version="1.0" encoding="utf-16"?><direct-logon-request xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema"><password>REMOVED</password></direct-logon-request>
< <?xml version="1.0" encoding="utf-8"?><direct-logon-response state="ok" sid="1"><installationname>WolfLink</installationname><serialnumber>REMOVED2</serialnumber><date-time>2023-07-15T12:09:53</date-time><ism-softwareversion>300.10.1.300.300.1</ism-softwareversion><ism-hardwareversion>3.0</ism-hardwareversion><type>WOLFLink</type></direct-logon-response>
> <?xml version="1.0" encoding="utf-16"?><read-systemconfig-request xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema" sid="1" />
< <?xml version="1.0" encoding="utf-8"?><read-systemconfig-response sid="1"><gateway type="WOLFLink" softwareNumber="1" softwareVersion="300" wlan="false" g3="false"/><busconfig type="ebus"><busDevices><busDevice ba="0x8" sv="0x1" sr="0x50" cfg="0x440" did="0x29"/><busDevice ba="0x35" sv="0x2" sr="0x4" cfg="0xC000" did="0x20"/><busDevice ba="0x51" sv="0x1" sr="0x0" cfg="0x10" did="0x14"/></busDevices></busconfig><modbusconfig><busDevices></busDevices></modbusconfig></read-systemconfig-response>
System.InvalidOperationException: Sequence contains no matching element
at System.Linq.ThrowHelper.ThrowNoMatchException()
at ism7mqtt.Ism7Config.AddDevice(String ip, String ba) in /app/ISM7/Ism7Config.cs:line 76
at ism7mqtt.Ism7Client.LoadInitialValuesAsync(CancellationToken cancellationToken) in /app/ISM7/Ism7Client.cs:line 281
at ism7mqtt.ResponseDispatcher.DispatchAsync(IResponse response, CancellationToken cancellationToken) in /app/ISM7/ResponseDispatcher.cs:line 32
at ism7mqtt.Ism7Client.ReadPipeAsync(PipeReader source, CancellationToken cancellationToken) in /app/ISM7/Ism7Client.cs:line 213
zivillian commented
Which docker tag are you using? If I'm correct you are using latest which is not compatible with the config of v0.0.13 - so you should either use v0.0.13 or master
IgitBuh commented
This is unexpected. In my experience the latest docker image always refers to the latest official release, which in this case here should be the same as v0.0.13. Is it not? If this is really the case, then please consider adding something like latest-beta (or latest-nightly or master which you already have) for images that are not an official release. Please notice that the releases page also marks v0.0.13 with the "Latest" label.
Okay, since there are no compiled versions of ism7config for the current master code, I guess I'll now install the v0.0.13 docker image instead.
IgitBuh commented
OK, switching out the docker container from latest to v0.0.13 seemed to do the trick. Thanks!
(Although I got an error first and had to change DOTNET_SYSTEM_GLOBALIZATION_INVARIANT from false to true. In the latest image this value is already true by default.)
zivillian commented
Great - I'll update the latest tag to also point to the latest release. I haven't done this before, because I haven't got any feedback if this still works (besides my own setup).
Which error do you get when setting DOTNET_SYSTEM_GLOBALIZATION_INVARIANT=false? This was needed to get ism7config working inside docker.
IgitBuh commented
Which error do you get when setting
DOTNET_SYSTEM_GLOBALIZATION_INVARIANT=false? This was needed to get ism7config working inside docker.
Process terminated. Couldn't find a valid ICU package installed on the system. Please install libicu using your package manager and try again. Alternatively you can set the configuration flag System.Globalization.Invariant to true if you want to run with no globalization support. Please see https://aka.ms/dotnet-missing-libicu for more information.
at System.Environment.FailFast(System.String)
at System.Globalization.GlobalizationMode+Settings..cctor()
at System.Globalization.CultureData.CreateCultureWithInvariantData()
at System.Globalization.CultureData.get_Invariant()
at System.Globalization.CultureInfo..cctor()
at System.Globalization.CultureInfo.get_CurrentCulture()
at System.ComponentModel.BaseNumberConverter.ConvertFrom(System.ComponentModel.ITypeDescriptorContext, System.Globalization.CultureInfo, System.Object)
at System.ComponentModel.TypeConverter.ConvertFromString(System.String)
at ism7mqtt.Program.GetEnvInt32(System.String, Int32)
at ism7mqtt.Program+<Main>d__3.MoveNext()
at System.Runtime.CompilerServices.AsyncMethodBuilderCore.Start[[ism7mqtt.Program+<Main>d__3, ism7mqtt, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null]](<Main>d__3 ByRef)
at System.Runtime.CompilerServices.AsyncTaskMethodBuilder.Start[[ism7mqtt.Program+<Main>d__3, ism7mqtt, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null]](<Main>d__3 ByRef)
at ism7mqtt.Program.Main(System.String[])
at ism7mqtt.Program.<Main>(System.String[])
fwiw: I'm running Docker on my Synology NAS.
IgitBuh commented
I am indescribably grateful for this project that you have set up here. I'm sure this was a huge amount of work that you have done here over the past 2+ years. It was a pain not having the CHA data in my network since it was installed. Now I have a new playground. Thank you!
Can I invite you for a beer and/or meal next time I come to Berlin (could be next year)?
zivillian commented
I've updated the latest tag and also changed the invariant globalization, so you don't need to set DOTNET_SYSTEM_GLOBALIZATION_INVARIANT anymore.
IgitBuh commented
I switched to the latest docker image and can confirm that it's working now as expected. Thanks!