zivillian / ism7mqtt

GNU General Public License v3.0
49 stars 8 forks source link

System.InvalidOperationException: Sequence contains no matching element #62

Closed IgitBuh closed 11 months ago

IgitBuh commented 11 months ago

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 11 months ago

Can you share your parameter.json and the debug log?

IgitBuh commented 11 months ago

Yes sure:

parameter.json ```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| Written XML, bytes:118, msg: REMOVED 2023-07-15 01:21:14.0336|INFO|LuCon.WebPortal.StandaloneService.NetworkConnector|Got XML msg: WolfLink REMOVED2 2023-07-15T01:21:25 300.10.1.300.300.1 3.0 WOLFLink 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| Written XML, bytes:81, msg: 2023-07-15 01:21:14.3650|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:21:14.3650|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=4 2023-07-15 01:21:14.3650|TRACE|GW-1| lenToRead: 500 2023-07-15 01:21:14.3733|TRACE|GW-1| telegrType SystemconfigResponse 2023-07-15 01:21:14.3733|TRACE|GW-1| Try to complete msg, bytesMissing:500 [6/500] 2023-07-15 01:21:14.4397|TRACE|GW-1| Got 266 bytes from stream, elapsedMs=73 2023-07-15 01:21:14.4397|TRACE|GW-1| Try to complete msg, bytesMissing:234 [272/500] 2023-07-15 01:21:14.5197|TRACE|GW-1| Got 126 bytes from stream, elapsedMs=153 2023-07-15 01:21:14.5197|TRACE|GW-1| Try to complete msg, bytesMissing:108 [398/500] 2023-07-15 01:21:14.5996|TRACE|GW-1| Got 108 bytes from stream, elapsedMs=233 2023-07-15 01:21:14.5996|INFO|GW-1| Got XML msg:??? 2023-07-15 01:21:14.6987|INFO|GW-1| - ProcessSystemconfigResponse:SystemconfigResponse, SID:1 2023-07-15 01:21:14.8562|INFO|GW-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| Written XML, bytes:631, msg: 2023-07-15 01:21:23.6349|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:21:23.6349|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=0 2023-07-15 01:21:23.6383|TRACE|GW-1| lenToRead: 854 2023-07-15 01:21:23.6383|TRACE|GW-1| telegrType TgrBundleResponse 2023-07-15 01:21:23.6383|TRACE|GW-1| Try to complete msg, bytesMissing:854 [6/854] 2023-07-15 01:21:23.7149|TRACE|GW-1| Got 166 bytes from stream, elapsedMs=79 2023-07-15 01:21:23.7153|TRACE|GW-1| Try to complete msg, bytesMissing:688 [172/854] 2023-07-15 01:21:23.7948|TRACE|GW-1| Got 124 bytes from stream, elapsedMs=159 2023-07-15 01:21:23.7948|TRACE|GW-1| Try to complete msg, bytesMissing:564 [296/854] 2023-07-15 01:21:23.8747|TRACE|GW-1| Got 123 bytes from stream, elapsedMs=239 2023-07-15 01:21:23.8747|TRACE|GW-1| Try to complete msg, bytesMissing:441 [419/854] 2023-07-15 01:21:23.9549|TRACE|GW-1| Got 123 bytes from stream, elapsedMs=319 2023-07-15 01:21:23.9549|TRACE|GW-1| Try to complete msg, bytesMissing:318 [542/854] 2023-07-15 01:21:24.0345|TRACE|GW-1| Got 63 bytes from stream, elapsedMs=399 2023-07-15 01:21:24.0345|TRACE|GW-1| Try to complete msg, bytesMissing:255 [605/854] 2023-07-15 01:21:24.0747|TRACE|GW-1| Got 62 bytes from stream, elapsedMs=439 2023-07-15 01:21:24.0747|TRACE|GW-1| Try to complete msg, bytesMissing:193 [667/854] 2023-07-15 01:21:24.1144|TRACE|GW-1| Got 63 bytes from stream, elapsedMs=479 2023-07-15 01:21:24.1144|TRACE|GW-1| Try to complete msg, bytesMissing:130 [730/854] 2023-07-15 01:21:24.1544|TRACE|GW-1| Got 61 bytes from stream, elapsedMs=519 2023-07-15 01:21:24.1544|TRACE|GW-1| Try to complete msg, bytesMissing:69 [791/854] 2023-07-15 01:21:24.1947|TRACE|GW-1| Got 61 bytes from stream, elapsedMs=559 2023-07-15 01:21:24.1947|TRACE|GW-1| Try to complete msg, bytesMissing:8 [852/854] 2023-07-15 01:21:24.2339|TRACE|GW-1| Got 8 bytes from stream, elapsedMs=598 2023-07-15 01:21:24.2339|INFO|GW-1| Got XML msg:??? 2023-07-15 01:21:24.3712|INFO|GW-1| - UpdateBusconfigStep1 2023-07-15 01:21:24.4728|INFO|GW-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| - UpdateBusconfigStep2 2023-07-15 01:21:24.4975|INFO|GW-1| Written XML, bytes:1018, msg: 2023-07-15 01:21:26.9547|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:21:26.9547|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=0 2023-07-15 01:21:26.9547|TRACE|GW-1| lenToRead: 1259 2023-07-15 01:21:26.9547|TRACE|GW-1| telegrType TgrBundleResponse 2023-07-15 01:21:26.9547|TRACE|GW-1| Try to complete msg, bytesMissing:1259 [6/1259] 2023-07-15 01:21:27.0351|TRACE|GW-1| Got 166 bytes from stream, elapsedMs=80 2023-07-15 01:21:27.0351|TRACE|GW-1| Try to complete msg, bytesMissing:1093 [172/1259] 2023-07-15 01:21:27.1204|TRACE|GW-1| Got 44 bytes from stream, elapsedMs=165 2023-07-15 01:21:27.1204|TRACE|GW-1| Try to complete msg, bytesMissing:1049 [216/1259] 2023-07-15 01:21:27.1546|TRACE|GW-1| Got 44 bytes from stream, elapsedMs=199 2023-07-15 01:21:27.1546|TRACE|GW-1| Try to complete msg, bytesMissing:1005 [260/1259] 2023-07-15 01:21:27.1945|TRACE|GW-1| Got 44 bytes from stream, elapsedMs=239 2023-07-15 01:21:27.1945|TRACE|GW-1| Try to complete msg, bytesMissing:961 [304/1259] 2023-07-15 01:21:27.2344|TRACE|GW-1| Got 62 bytes from stream, elapsedMs=279 2023-07-15 01:21:27.2344|TRACE|GW-1| Try to complete msg, bytesMissing:899 [366/1259] 2023-07-15 01:21:27.2744|TRACE|GW-1| Got 63 bytes from stream, elapsedMs=319 2023-07-15 01:21:27.2744|TRACE|GW-1| Try to complete msg, bytesMissing:836 [429/1259] 2023-07-15 01:21:27.3147|TRACE|GW-1| Got 76 bytes from stream, elapsedMs=359 2023-07-15 01:21:27.3147|TRACE|GW-1| Try to complete msg, bytesMissing:760 [505/1259] 2023-07-15 01:21:27.3544|TRACE|GW-1| Got 76 bytes from stream, elapsedMs=399 2023-07-15 01:21:27.3544|TRACE|GW-1| Try to complete msg, bytesMissing:684 [581/1259] 2023-07-15 01:21:27.3947|TRACE|GW-1| Got 63 bytes from stream, elapsedMs=439 2023-07-15 01:21:27.3947|TRACE|GW-1| Try to complete msg, bytesMissing:621 [644/1259] 2023-07-15 01:21:27.4344|TRACE|GW-1| Got 63 bytes from stream, elapsedMs=479 2023-07-15 01:21:27.4344|TRACE|GW-1| Try to complete msg, bytesMissing:558 [707/1259] 2023-07-15 01:21:27.4745|TRACE|GW-1| Got 64 bytes from stream, elapsedMs=519 2023-07-15 01:21:27.4745|TRACE|GW-1| Try to complete msg, bytesMissing:494 [771/1259] 2023-07-15 01:21:27.5147|TRACE|GW-1| Got 43 bytes from stream, elapsedMs=559 2023-07-15 01:21:27.5147|TRACE|GW-1| Try to complete msg, bytesMissing:451 [814/1259] 2023-07-15 01:21:27.5548|TRACE|GW-1| Got 107 bytes from stream, elapsedMs=599 2023-07-15 01:21:27.5548|TRACE|GW-1| Try to complete msg, bytesMissing:344 [921/1259] 2023-07-15 01:21:27.6348|TRACE|GW-1| Got 125 bytes from stream, elapsedMs=679 2023-07-15 01:21:27.6348|TRACE|GW-1| Try to complete msg, bytesMissing:219 [1046/1259] 2023-07-15 01:21:27.7148|TRACE|GW-1| Got 88 bytes from stream, elapsedMs=759 2023-07-15 01:21:27.7148|TRACE|GW-1| Try to complete msg, bytesMissing:131 [1134/1259] 2023-07-15 01:21:27.7948|TRACE|GW-1| Got 123 bytes from stream, elapsedMs=839 2023-07-15 01:21:27.7948|TRACE|GW-1| Try to complete msg, bytesMissing:8 [1257/1259] 2023-07-15 01:21:27.8782|TRACE|GW-1| Got 8 bytes from stream, elapsedMs=923 2023-07-15 01:21:27.8782|INFO|GW-1| Got XML msg:??? 2023-07-15 01:21:27.8921|WARN|GW-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| - 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| - 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| - 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| - 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| - 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| - 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| Written XML, bytes:184, msg: 2023-07-15 01:21:29.1259|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:21:29.1259|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=0 2023-07-15 01:21:29.1259|TRACE|GW-1| lenToRead: 219 2023-07-15 01:21:29.1259|TRACE|GW-1| telegrType TgrBundleResponse 2023-07-15 01:21:29.1259|TRACE|GW-1| Try to complete msg, bytesMissing:219 [6/219] 2023-07-15 01:21:29.2059|TRACE|GW-1| Got 148 bytes from stream, elapsedMs=79 2023-07-15 01:21:29.2059|TRACE|GW-1| Try to complete msg, bytesMissing:71 [154/219] 2023-07-15 01:21:29.2857|TRACE|GW-1| Got 71 bytes from stream, elapsedMs=159 2023-07-15 01:21:29.2857|INFO|GW-1| Got XML msg:??? 2023-07-15 01:21:29.2857|WARN|GW-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| - Missing device answer - ParameterId:36013200001, state:Error 2023-07-15 01:21:29.2934|ERROR|GW-1| - Missing device answer - ParameterId:36014500001, state:Error 2023-07-15 01:21:29.2934|ERROR|GW-1| - Missing device answer - ParameterId:34005000000, state:Error 2023-07-15 01:21:29.2934|ERROR|GW-1| - Missing device answer - ParameterId:4009300001, state:Error 2023-07-15 01:21:29.2934|ERROR|GW-1| - Missing device answer - ParameterId:34004500000, state:Error 2023-07-15 01:21:29.2934|ERROR|GW-1| - Missing device answer - ParameterId:36013600001, state:Error 2023-07-15 01:21:29.2934|ERROR|GW-1| - Missing device answer - ParameterId:34005500000, state:Error 2023-07-15 01:21:29.2934|ERROR|GW-1| - Missing device answer - ParameterId:36006600001, state:Error 2023-07-15 01:21:29.3133|INFO|GW-1| - FinishBusconfig 2023-07-15 01:21:29.3821|DEBUG|GW-1| BundleType:push, deviceVisibleReadAddresses:0x8,0x35,0x51 2023-07-15 01:21:29.3821|INFO|GW-1| Written XML, bytes:127, msg: 2023-07-15 01:21:29.4590|DEBUG|GW-1| Detect SmartHome-System... 2023-07-15 01:21:29.4590|DEBUG|GW-1| SmartHome = 0 2023-07-15 01:21:29.6998|INFO|GW-1| Written XML, bytes:1047, msg: 2023-07-15 01:21:41.4549|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:21:41.4549|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=0 2023-07-15 01:21:41.4549|TRACE|GW-1| lenToRead: 1414 2023-07-15 01:21:41.4549|TRACE|GW-1| telegrType TgrBundleResponse 2023-07-15 01:21:41.4549|TRACE|GW-1| Try to complete msg, bytesMissing:1414 [6/1414] 2023-07-15 01:21:41.5351|TRACE|GW-1| Got 171 bytes from stream, elapsedMs=79 2023-07-15 01:21:41.5351|TRACE|GW-1| Try to complete msg, bytesMissing:1243 [177/1414] 2023-07-15 01:21:41.6151|TRACE|GW-1| Got 149 bytes from stream, elapsedMs=159 2023-07-15 01:21:41.6151|TRACE|GW-1| Try to complete msg, bytesMissing:1094 [326/1414] 2023-07-15 01:21:41.6951|TRACE|GW-1| Got 128 bytes from stream, elapsedMs=239 2023-07-15 01:21:41.6951|TRACE|GW-1| Try to complete msg, bytesMissing:966 [454/1414] 2023-07-15 01:21:41.7750|TRACE|GW-1| Got 132 bytes from stream, elapsedMs=319 2023-07-15 01:21:41.7750|TRACE|GW-1| Try to complete msg, bytesMissing:834 [586/1414] 2023-07-15 01:21:41.8551|TRACE|GW-1| Got 129 bytes from stream, elapsedMs=399 2023-07-15 01:21:41.8551|TRACE|GW-1| Try to complete msg, bytesMissing:705 [715/1414] 2023-07-15 01:21:41.9351|TRACE|GW-1| Got 124 bytes from stream, elapsedMs=479 2023-07-15 01:21:41.9351|TRACE|GW-1| Try to complete msg, bytesMissing:581 [839/1414] 2023-07-15 01:21:42.0149|TRACE|GW-1| Got 128 bytes from stream, elapsedMs=559 2023-07-15 01:21:42.0149|TRACE|GW-1| Try to complete msg, bytesMissing:453 [967/1414] 2023-07-15 01:21:42.0948|TRACE|GW-1| Got 129 bytes from stream, elapsedMs=639 2023-07-15 01:21:42.0948|TRACE|GW-1| Try to complete msg, bytesMissing:324 [1096/1414] 2023-07-15 01:21:42.1754|TRACE|GW-1| Got 121 bytes from stream, elapsedMs=720 2023-07-15 01:21:42.1754|TRACE|GW-1| Try to complete msg, bytesMissing:203 [1217/1414] 2023-07-15 01:21:42.2548|TRACE|GW-1| Got 133 bytes from stream, elapsedMs=799 2023-07-15 01:21:42.2548|TRACE|GW-1| Try to complete msg, bytesMissing:70 [1350/1414] 2023-07-15 01:21:42.3349|TRACE|GW-1| Got 70 bytes from stream, elapsedMs=879 2023-07-15 01:21:42.3349|INFO|GW-1| Got XML msg:??? 2023-07-15 01:21:42.4263|INFO|GW-1| Written XML, bytes:1082, msg: 2023-07-15 01:21:55.5179|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:21:55.5179|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=0 2023-07-15 01:21:55.5179|TRACE|GW-1| lenToRead: 1456 2023-07-15 01:21:55.5179|TRACE|GW-1| telegrType TgrBundleResponse 2023-07-15 01:21:55.5179|TRACE|GW-1| Try to complete msg, bytesMissing:1456 [6/1456] 2023-07-15 01:21:55.5980|TRACE|GW-1| Got 169 bytes from stream, elapsedMs=79 2023-07-15 01:21:55.5980|TRACE|GW-1| Try to complete msg, bytesMissing:1287 [175/1456] 2023-07-15 01:21:55.6780|TRACE|GW-1| Got 136 bytes from stream, elapsedMs=159 2023-07-15 01:21:55.6780|TRACE|GW-1| Try to complete msg, bytesMissing:1151 [311/1456] 2023-07-15 01:21:55.7582|TRACE|GW-1| Got 126 bytes from stream, elapsedMs=240 2023-07-15 01:21:55.7582|TRACE|GW-1| Try to complete msg, bytesMissing:1025 [437/1456] 2023-07-15 01:21:55.8380|TRACE|GW-1| Got 135 bytes from stream, elapsedMs=319 2023-07-15 01:21:55.8380|TRACE|GW-1| Try to complete msg, bytesMissing:890 [572/1456] 2023-07-15 01:21:55.9181|TRACE|GW-1| Got 137 bytes from stream, elapsedMs=399 2023-07-15 01:21:55.9181|TRACE|GW-1| Try to complete msg, bytesMissing:753 [709/1456] 2023-07-15 01:21:56.0010|TRACE|GW-1| Got 134 bytes from stream, elapsedMs=482 2023-07-15 01:21:56.0010|TRACE|GW-1| Try to complete msg, bytesMissing:619 [843/1456] 2023-07-15 01:21:56.0780|TRACE|GW-1| Got 134 bytes from stream, elapsedMs=559 2023-07-15 01:21:56.0780|TRACE|GW-1| Try to complete msg, bytesMissing:485 [977/1456] 2023-07-15 01:21:56.1579|TRACE|GW-1| Got 127 bytes from stream, elapsedMs=639 2023-07-15 01:21:56.1579|TRACE|GW-1| Try to complete msg, bytesMissing:358 [1104/1456] 2023-07-15 01:21:56.2379|TRACE|GW-1| Got 131 bytes from stream, elapsedMs=719 2023-07-15 01:21:56.2379|TRACE|GW-1| Try to complete msg, bytesMissing:227 [1235/1456] 2023-07-15 01:21:56.3182|TRACE|GW-1| Got 143 bytes from stream, elapsedMs=800 2023-07-15 01:21:56.3182|TRACE|GW-1| Try to complete msg, bytesMissing:84 [1378/1456] 2023-07-15 01:21:56.3981|TRACE|GW-1| Got 84 bytes from stream, elapsedMs=879 2023-07-15 01:21:56.3981|INFO|GW-1| Got XML msg:??? 2023-07-15 01:21:56.4125|INFO|GW-1| Written XML, bytes:1032, msg: 2023-07-15 01:21:56.7380|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:21:56.7380|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=0 2023-07-15 01:21:56.7380|TRACE|GW-1| lenToRead: 86 2023-07-15 01:21:56.7380|TRACE|GW-1| telegrType TgrBundleResponse 2023-07-15 01:21:56.7380|TRACE|GW-1| Try to complete msg, bytesMissing:86 [6/86] 2023-07-15 01:21:56.7827|TRACE|GW-1| Got 86 bytes from stream, elapsedMs=44 2023-07-15 01:21:56.7827|INFO|GW-1| Got XML msg:??? 2023-07-15 01:22:04.2101|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:22:04.2101|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=0 2023-07-15 01:22:04.2101|TRACE|GW-1| lenToRead: 1398 2023-07-15 01:22:04.2162|TRACE|GW-1| telegrType TgrBundleResponse 2023-07-15 01:22:04.2162|TRACE|GW-1| Try to complete msg, bytesMissing:1398 [6/1398] 2023-07-15 01:22:04.2901|TRACE|GW-1| Got 170 bytes from stream, elapsedMs=79 2023-07-15 01:22:04.2901|TRACE|GW-1| Try to complete msg, bytesMissing:1228 [176/1398] 2023-07-15 01:22:04.3700|TRACE|GW-1| Got 140 bytes from stream, elapsedMs=159 2023-07-15 01:22:04.3700|TRACE|GW-1| Try to complete msg, bytesMissing:1088 [316/1398] 2023-07-15 01:22:04.4504|TRACE|GW-1| Got 138 bytes from stream, elapsedMs=239 2023-07-15 01:22:04.4504|TRACE|GW-1| Try to complete msg, bytesMissing:950 [454/1398] 2023-07-15 01:22:04.5300|TRACE|GW-1| Got 123 bytes from stream, elapsedMs=319 2023-07-15 01:22:04.5300|TRACE|GW-1| Try to complete msg, bytesMissing:827 [577/1398] 2023-07-15 01:22:04.6100|TRACE|GW-1| Got 67 bytes from stream, elapsedMs=399 2023-07-15 01:22:04.6100|TRACE|GW-1| Try to complete msg, bytesMissing:760 [644/1398] 2023-07-15 01:22:04.6498|TRACE|GW-1| Got 67 bytes from stream, elapsedMs=439 2023-07-15 01:22:04.6498|TRACE|GW-1| Try to complete msg, bytesMissing:693 [711/1398] 2023-07-15 01:22:04.6900|TRACE|GW-1| Got 60 bytes from stream, elapsedMs=479 2023-07-15 01:22:04.6900|TRACE|GW-1| Try to complete msg, bytesMissing:633 [771/1398] 2023-07-15 01:22:04.7297|TRACE|GW-1| Got 65 bytes from stream, elapsedMs=519 2023-07-15 01:22:04.7297|TRACE|GW-1| Try to complete msg, bytesMissing:568 [836/1398] 2023-07-15 01:22:04.7699|TRACE|GW-1| Got 61 bytes from stream, elapsedMs=559 2023-07-15 01:22:04.7699|TRACE|GW-1| Try to complete msg, bytesMissing:507 [897/1398] 2023-07-15 01:22:04.8098|TRACE|GW-1| Got 60 bytes from stream, elapsedMs=599 2023-07-15 01:22:04.8098|TRACE|GW-1| Try to complete msg, bytesMissing:447 [957/1398] 2023-07-15 01:22:04.8493|TRACE|GW-1| Got 62 bytes from stream, elapsedMs=638 2023-07-15 01:22:04.8493|TRACE|GW-1| Try to complete msg, bytesMissing:385 [1019/1398] 2023-07-15 01:22:04.8897|TRACE|GW-1| Got 64 bytes from stream, elapsedMs=679 2023-07-15 01:22:04.8897|TRACE|GW-1| Try to complete msg, bytesMissing:321 [1083/1398] 2023-07-15 01:22:04.9324|TRACE|GW-1| Got 64 bytes from stream, elapsedMs=721 2023-07-15 01:22:04.9324|TRACE|GW-1| Try to complete msg, bytesMissing:257 [1147/1398] 2023-07-15 01:22:04.9748|TRACE|GW-1| Got 126 bytes from stream, elapsedMs=764 2023-07-15 01:22:04.9748|TRACE|GW-1| Try to complete msg, bytesMissing:131 [1273/1398] 2023-07-15 01:22:05.0511|TRACE|GW-1| Got 123 bytes from stream, elapsedMs=840 2023-07-15 01:22:05.0511|TRACE|GW-1| Try to complete msg, bytesMissing:8 [1396/1398] 2023-07-15 01:22:05.1347|TRACE|GW-1| Got 8 bytes from stream, elapsedMs=924 2023-07-15 01:22:05.1347|INFO|GW-1| Got XML msg:??? 2023-07-15 01:22:05.1668|INFO|GW-1| Written XML, bytes:371, msg: 2023-07-15 01:22:06.6820|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:22:06.6820|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=0 2023-07-15 01:22:06.6860|TRACE|GW-1| lenToRead: 495 2023-07-15 01:22:06.6860|TRACE|GW-1| telegrType TgrBundleResponse 2023-07-15 01:22:06.6860|TRACE|GW-1| Try to complete msg, bytesMissing:495 [6/495] 2023-07-15 01:22:06.7618|TRACE|GW-1| Got 106 bytes from stream, elapsedMs=79 2023-07-15 01:22:06.7618|TRACE|GW-1| Try to complete msg, bytesMissing:389 [112/495] 2023-07-15 01:22:06.8018|TRACE|GW-1| Got 62 bytes from stream, elapsedMs=119 2023-07-15 01:22:06.8018|TRACE|GW-1| Try to complete msg, bytesMissing:327 [174/495] 2023-07-15 01:22:06.8424|TRACE|GW-1| Got 127 bytes from stream, elapsedMs=160 2023-07-15 01:22:06.8424|TRACE|GW-1| Try to complete msg, bytesMissing:200 [301/495] 2023-07-15 01:22:06.9221|TRACE|GW-1| Got 130 bytes from stream, elapsedMs=239 2023-07-15 01:22:06.9438|TRACE|GW-1| Try to complete msg, bytesMissing:70 [431/495] 2023-07-15 01:22:07.0022|TRACE|GW-1| Got 70 bytes from stream, elapsedMs=319 2023-07-15 01:22:07.0022|INFO|GW-1| Got XML msg:??? 2023-07-15 01:22:07.1339|INFO|GW-1| Written XML, bytes:139, msg: 2023-07-15 01:22:07.4314|TRACE|GW-1| ReadTelegramAsync !! 2023-07-15 01:22:07.4314|TRACE|GW-1| Got 6 bytes from stream, elapsedMs=0 2023-07-15 01:22:07.4376|TRACE|GW-1| lenToRead: 174 2023-07-15 01:22:07.4376|TRACE|GW-1| telegrType TgrBundleResponse 2023-07-15 01:22:07.4376|TRACE|GW-1| Try to complete msg, bytesMissing:174 [6/174] 2023-07-15 01:22:07.5112|TRACE|GW-1| Got 166 bytes from stream, elapsedMs=79 2023-07-15 01:22:07.5112|TRACE|GW-1| Try to complete msg, bytesMissing:8 [172/174] 2023-07-15 01:22:07.5957|TRACE|GW-1| Got 8 bytes from stream, elapsedMs=164 2023-07-15 01:22:07.5957|INFO|GW-1| Got XML msg:??? 2023-07-15 01:22:07.6118|INFO|GW-1| - FinishBusconfig, gw is online now 2023-07-15 01:22:07.6118|INFO|GW-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 11 months ago

Where can I find the debug log that you would like to see?

just add an environment variable ISM7_DEBUG=true

IgitBuh commented 11 months ago

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 11 months ago

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 11 months ago

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 11 months ago

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 11 months ago

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 11 months ago

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 11 months ago

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 11 months ago

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 11 months ago

I switched to the latest docker image and can confirm that it's working now as expected. Thanks!