Skip to content

Cancelation of ism7mqtt after timeout #181

@frankkaktus

Description

@frankkaktus

Hello,

I've installed ism7mqtt on a linux/amd64 platform using docker-compose. The parameter.json was generated and delivers 8 devices with the IDs 190000, 220000, 340000, 350000, 390000, 40000, 270000 and 360000. Starting the container ism7mqtt task will be cancelled after about 1 min caused by a timeout:

2025-12-25T09:54:55.363109941Z < <tbres bn="3" gw="1" st="OK" ts="2025-12-25T10:59:45" emsg="">
2025-12-25T09:54:55.363190512Z   <irs se="A;147" ba="0x51" in="82" dl="0x1" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363212630Z   <irs se="A;148" ba="0x51" in="86" dl="0x1" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363233060Z   <irs se="A;149" ba="0x51" in="87" dl="0x0" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363253239Z   <irs se="A;150" ba="0x51" in="4" dl="0x4B" dh="0x1" st="OK"/>
2025-12-25T09:54:55.363289206Z   <irs se="A;151" ba="0x51" in="299" dl="0xC8" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363309978Z   <irs se="A;152" ba="0x51" in="40" dl="0xF4" dh="0x1" st="OK"/>
2025-12-25T09:54:55.363329938Z   <irs se="A;153" ba="0x51" in="273" dl="0x32" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363349909Z   <irs se="A;154" ba="0x51" in="2593" dl="0x8" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363370086Z   <irs se="A;155" ba="0x51" in="322" dl="0x5" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363390218Z   <irs se="A;156" ba="0x51" in="399" dl="0x78" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363410194Z   <irs se="A;157" ba="0x51" in="65019" dl="0x2" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363430581Z   <irs se="A;158" ba="0x51" in="15" dl="0x45" dh="0x1" st="OK"/>
2025-12-25T09:54:55.363450793Z   <irs se="A;159" ba="0x51" in="410" dl="0x0" dh="0x0" st="OK"/>
2025-12-25T09:54:55.363470471Z </tbres>
2025-12-25T09:54:55.364606455Z publishing mqtt with topic 'Wolf/10.1.20.3/MM_0x51' '{"Ausgang MKP":{"value":1,"text":"Ein"},"Mischermotor":{"value":2,"text":"Auf"},"Mischersolltemperatur":33.1,"Minimalbegrenzung Mischerkreis TV-min":20,"Maximalbegrenzung Mischerkreis TV-max":50,"Heizkurvenabstand":5,"Konfiguration":8,"Nachlaufzeit Heizkreis":5,"P-Bereich Mischer":12,"eBus Einspeisung":{"value":2,"text":"Auto"},"Analogeingang Vorlauff\u00FChler VF":32.5,"Mischerkreispumpe":{"value":1,"text":"Ein"},"PRELOAD_Firmware":0}'
2025-12-25T09:54:55.365057764Z > <?xml version="1.0" encoding="utf-8"?><tbreq bn="8" gw="1" ae="false" ty="push"><ird in="82" se="A;496" ba="0x51" is="60" /><ird in="86" se="A;497" ba="0x51" is="60" /><ird in="87" se="A;498" ba="0x51" is="60" /><ird in="4" se="A;499" ba="0x51" is="60" /><ird in="299" se="A;500" ba="0x51" is="60" /><ird in="40" se="A;501" ba="0x51" is="60" /><ird in="273" se="A;502" ba="0x51" is="60" /><ird in="2593" se="A;503" ba="0x51" is="60" /><ird in="322" se="A;504" ba="0x51" is="60" /><ird in="399" se="A;505" ba="0x51" is="60" /><ird in="65019" se="A;506" ba="0x51" is="60" /><ird in="15" se="A;507" ba="0x51" is="60" /><ird in="410" se="A;508" ba="0x51" is="60" /></tbreq>
2025-12-25T09:55:10.036122688Z > ��
2025-12-25T09:55:10.096643985Z < ��
2025-12-25T09:55:25.323856958Z < <tbres bn="4" gw="1" st="ER" ts="2025-12-25T11:00:15" emsg="timeout"/>
2025-12-25T09:55:25.360150328Z System.IO.InvalidDataException: timeout
2025-12-25T09:55:25.360241785Z    at ism7mqtt.Ism7Client.OnInitialValuesAsync(IResponse response, CancellationToken cancellationToken) in /app/ism7mqtt/ISM7/Ism7Client.cs:line 297
2025-12-25T09:55:25.360285573Z    at ism7mqtt.ResponseDispatcher.DispatchAsync(IResponse response, CancellationToken cancellationToken) in /app/ism7mqtt/ISM7/ResponseDispatcher.cs:line 32
2025-12-25T09:55:25.360336020Z    at ism7mqtt.Ism7Client.ReadPipeAsync(PipeReader source, CancellationToken cancellationToken) in /app/ism7mqtt/ISM7/Ism7Client.cs:line 191
2025-12-25T09:55:25.374560480Z System.Net.Sockets.SocketException (125): Operation canceled
2025-12-25T09:55:25.375129608Z    at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
2025-12-25T09:55:25.375190365Z    at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
2025-12-25T09:55:25.375213582Z    at ism7ssl.Ism7SslStream.ReadAsync(Memory`1 buffer, CancellationToken cancellationToken) in /app/ism7ssl/Ism7SslStream.cs:line 154
2025-12-25T09:55:25.375234698Z    at ism7mqtt.Ism7Client.FillPipeAsync(PipeWriter target, CancellationToken cancellationToken) in /app/ism7mqtt/ISM7/Ism7Client.cs:line 145

Deleting the recommended parameters (see hassio-addon-ism7mqtt) has no effect. Then I tried to reduce the number of parameters dramatically by deleting the devices 220000, 340000 and 270000, what makes ism7mqtt stable. In an additional test I've included only the devices 190000, 220000 and 270000, but this reproduces the timeout again.

Don't know, how to solve. Every idea is welcome...
Frank

Metadata

Metadata

Assignees

No one assigned

    Labels

    No labels
    No labels

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions