76_SMAInverter.pm - Abfrage von SMA Wechselrichter

Xguide · 29 November 2016, 09:36:30

Guten Morgen Heiko,

das war gestern eindeutig mein Fehler. Ich habe nur ein "rereadcfg" gemacht, was das Modul wohl nicht richtig geladen hat.
Nachdem heute die neuen Readings noch immer nicht da waren, hat ein "reload 76_SMAInverter.pm" geholfen.
Allerdings hatte danach mein Log wieder was aufzuweisen,

Code Auswählen


2016.11.29 09:10:45 1: PERL WARNING: Use of uninitialized value $averagebuf[0] in join or string at /opt/fhem//FHEM/76_SMAInverter.pm line 612.
2016.11.29 09:10:45 1: PERL WARNING: Use of uninitialized value $avgsum in addition (+) at /opt/fhem//FHEM/76_SMAInverter.pm line 618.
2016.11.29 09:10:45 1: PERL WARNING: Use of uninitialized value $inv_susyid in concatenation (.) or string at /opt/fhem//FHEM/76_SMAInverter.pm line 792.
2016.11.29 09:10:45 1: PERL WARNING: Use of uninitialized value $inv_serial in concatenation (.) or string at /opt/fhem//FHEM/76_SMAInverter.pm line 793.
2016.11.29 09:11:08 1: PERL WARNING: Argument "" isn't numeric in addition (+) at /opt/fhem//FHEM/76_SMAInverter.pm line 618.
2016.11.29 09:11:16 1: PERL WARNING: Argument "" isn't numeric in addition (+) at /opt/fhem//FHEM/76_SMAInverter.pm line 618.
2016.11.29 09:11:47 1: PERL WARNING: Argument "" isn't numeric in addition (+) at /opt/fhem//FHEM/76_SMAInverter.pm line 618.
2016.11.29 09:12:16 1: PERL WARNING: Argument "" isn't numeric in addition (+) at /opt/fhem//FHEM/76_SMAInverter.pm line 618.

Nun habe ich noch mal ein "shutdown restart" und es sieht besser aus!

Anbei noch mal ein vollständiges Log von meinem SMA STP10000TL-20 PV-WR auf verbose 5.
Der INV_TYPE wird nur richtig angezeigt, da ich "9098 => "STP 10000TL-20" dem Array "SMAInverter_devtypes" hinzufüge. Vielleicht findet sich ja mal jemand der den gleichen WR hat!

Code Auswählen


2016.11.29 09:25:14 4: SMAInverter SMA_TriPower10 -> Start BlockingCall getstatus_DoParse
2016.11.29 09:25:14 4: SMA_TriPower10 - current time: 29.11.2016 09:25:14
2016.11.29 09:25:14 4: SMA_TriPower10 - operation time begin: 29.11.2016 07:33:46
2016.11.29 09:25:14 4: SMA_TriPower10 - operation time end: 29.11.2016 17:05:42
2016.11.29 09:25:14 4: SMA_TriPower10: Send login to xxx.xxx.xxx.xxx on Port 9522 with password 0000 
2016.11.29 09:25:14 5: SMA_TriPower10: Send: 534D4100000402A000000001003A001060650EA0FFFFFFFFFFFF0001E90023BB590700010000000001800C04FDFF07000000840300006A3B3D5800000000B8B8B8B8888888888888888800000000 
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a000000001003a001060650ed0e90023bb59070001b50060042e1200010000000001800d04fdff07000000840300006a3b3d5800000000b8b8b8b8888888888888888800000000
2016.11.29 09:25:14 5: SMA_TriPower10: Logged in now
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 00020058001E8200FF208200 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB5907000000000000028000020058001E8200FF20820000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a00000000100c6001060653190e90023bb590700a0b50060042e120000000000000280010200580000000003000000011e8210c3293d58534e3a2033303530303536363400000000000000000000000000000000000000011f8208c3293d58411f0001feffff0000000000000000000000000000000000000000000000000001208208c3293d588a2300008b2300008c2300008e2300008f23000041240001422400004324000001208208c3293d58feffff000000000000000000000000000000000000000000000000000000000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 33310
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data CLASS=8001 and TYPE=9098
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 0002005400012600FF222600 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB590700000000000003800002005400012600FF22260000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a0000000010046001060651190e90023bb590700a0b50060042e120000000000000380010200540000000001000000010126006b3b3d58898dcc000000000001222600693b3d58430500000000000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 9729
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data SPOT_ETOTAL=13405577 and SPOT_ETODAY=1347
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 00028053001E2500FF1E2500 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB5907000000000000048000028053001E2500FF1E250000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a000000001005e001060651790e90023bb590700a0b50060042e120000000000000480010280530000000001000000011e25406b3b3d58d8040000d8040000d8040000d804000001000000021e25406b3b3d58250400002504000025040000250400000100000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 9502
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data SPOT_PDC1=1240 and SPOT_PDC2=1061
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 0002005100404600FF424600 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB590700000000000005800002005100404600FF42460000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a000000001007a001060651e90e90023bb590700a0b50060042e120000000000000580010200510700000009000000014046406a3b3d580103000001030000010300000103000001000000014146406a3b3d580003000000030000000300000003000001000000014246406a3b3d58040300000403000004030000040300000100000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 17984
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data SPOT_PAC1=769 and SPOT_PAC2=768 and SPOT_PAC3=772
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 00020051003F2600FF3F2600 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB5907000000000000068000020051003F2600FF3F260000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a0000000010042001060651090e90023bb590700a0b50060042e120000000000000680010200510000000000000000013f26406a3b3d58050900000509000005090000050900000100000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 9791
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data SPOT_PACTOT=2309
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 00020051005A2900FF5A2900 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB5907000000000000078000020051005A2900FF5A290000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a0000000010026001060650990e90023bb590700e0b50060042e12000015000000078001020051005a2900ff5a290000000000
2016.11.29 09:25:14 3: SMA_TriPower10: Format of inverter response does not fit.
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 00028053001F4500FF214500 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB5907000000000000088000028053001F4500FF21450000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a0000000010096001060652590e90023bb590700a0b50060042e120000000000000880010280530200000005000000011f45406b3b3d58dad20000dad20000dad20000dad2000001000000021f45406b3b3d584701010047010100470101004701010001000000012145406b3b3d58fb080000fb080000fb080000fb08000001000000022145406b3b3d584c0600004c0600004c0600004c0600000100000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 17695
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data SPOT_UDC1=539.78 and SPOT_UDC2=658.63 and SPOT_IDC1=2.299 and SPOT_IDC2=1.612
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 0002005100484600FF554600 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB590700000000000009800002005100484600FF55460000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a00000000100ce001060653390e90023bb590700a0b50060042e120000000000000980010200510a0000000f000000014846006a3b3d58c35b0000c35b0000c35b0000c35b000001000000014946006a3b3d58755b0000755b0000755b0000755b000001000000014a46006a3b3d58415b0000415b0000415b0000415b000001000000015346406a3b3d58c90c0000c90c0000c90c0000c90c000001000000015446406a3b3d58d10c0000d10c0000d10c0000d10c000001000000015546406a3b3d58ec0c0000ec0c0000ec0c0000ec0c00000100000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 17992
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data SPOT_UAC1=234.91 and SPOT_UAC2=234.13 and SPOT_UAC3=233.61 and SPOT_IAC1=3.273 and SPOT_IAC2=3.281 and SPOT_IAC3=3.308
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 00020051001E4900FF5D4900 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB59070000000000000A8000020051001E4900FF5D490000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a0000000010026001060650990e90023bb590700e0b50060042e120000150000000a8001020051001e4900ff5d490000000000
2016.11.29 09:25:14 3: SMA_TriPower10: Format of inverter response does not fit.
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 0002005100574600FF574600 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB59070000000000000B800002005100574600FF57460000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a0000000010042001060651090e90023bb590700a0b50060042e120000000000000b80010200511000000010000000015746006a3b3d58851300008513000085130000851300000100000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 18007
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data SPOT_FREQ=49.97
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 00020054002E4600FF2F4600 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB59070000000000000C8000020054002E4600FF2F460000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a0000000010046001060651190e90023bb590700a0b50060042e120000000000000c80010200540300000004000000012e46006b3b3d581a81360100000000012f46006b3b3d5884c82f010000000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 17966
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data SPOT_OPERTM=5652.55 and SPOT_FEEDTM=5530.2
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 0002005200772300FF772300 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB59070000000000000D800002005200772300FF77230000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a0000000010042001060651090e90023bb590700a0b50060042e120000000000000d80010200520000000000000000017723405c3b3d58c211000013120000e3110000e31100000100000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 9079
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data Inverter Temp=45.46
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 00020051001E4100FF204100 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB59070000000000000E8000020051001E4100FF20410000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a000000001007a001060651e90e90023bb590700a0b50060042e120000000000000e80010200510100000003000000011e4100693b3d581027000010270000102700001027000001000000011f4100693b3d58102700001027000000000000102700000000000001204100693b3d58102700001027000000000000102700000000000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 16670
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data INV_PACMAX1=10000 and INV_PACMAX2=10000 and INV_PACMAX3=10000
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 00020051002A8300FF2A8300 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB59070000000000000F8000020051002A8300FF2A830000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a0000000010026001060650990e90023bb590700e0b50060042e120000150000000f8001020051002a8300ff2a830000000000
2016.11.29 09:25:14 3: SMA_TriPower10: Format of inverter response does not fit.
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 0002805100644100FF644100 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB590700000000000010800002805100644100FF64410000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a000000001004e001060651390e90023bb590700a0b50060042e120000000000001080010280510800000008000000016441086b3b3d583300000137010000fdffff00feffff000000000000000000000000000000000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 16740
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data INV_GRIDRELAY=51
2016.11.29 09:25:14 3: SMA_TriPower10: Send request 0002805100482100FF482100 to xxx.xxx.xxx.xxx on port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: send: 534D4100000402A00000000100260010606509A0FFFFFFFFFFFF0000E90023BB590700000000000011800002805100482100FF48210000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Received: 534d4100000402a000000001004e001060651390e90023bb590700a0b50060042e12000000000000118001028051000000000000000001482108693b3d58230000002f01000033010001c7010000feffff0000000000000000000000000000000000
2016.11.29 09:25:14 5: SMA_TriPower10: Data identifier 8520
2016.11.29 09:25:14 5: SMA_TriPower10: Found Data inv_STATUS=307
2016.11.29 09:25:14 4: SMA_TriPower10: Send logout to xxx.xxx.xxx.xxx on Port 9522
2016.11.29 09:25:14 5: SMA_TriPower10: Send: 534D4100000402A00000000100220010606508A0FFFFFFFFFFFF0003E90023BB590700030000000012800E01FDFFFFFFFFFF00000000 
2016.11.29 09:25:14 3: SMA_TriPower10: Logged out now.
2016.11.29 09:25:14 5: SMA_TriPower10: CNT05: 10 SUM05: 24059
2016.11.29 09:25:14 5: SMA_TriPower10: CNT10: 20 SUM10: 45336
2016.11.29 09:25:14 5: SMA_TriPower10: CNT15: 30 SUM15: 66536
2016.11.29 09:25:14 5: SMA_TriPower10: Content of Averagebuffer:
2016.11.29 09:25:14 5: SMA_TriPower10: 2309,2281,2247,2223,2233,2228,2148,2108,2088,2084,2110,2152,2165,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120,2120
2016.11.29 09:25:14 5: SMA_TriPower10: avg_power_lastminutes_05 = 2187, avg_power_lastminutes_10 = 2158, avg_power_lastminutes_15 = 2146
2016.11.29 09:25:14 5: SMA_TriPower10 -> row_array before encoding:
2016.11.29 09:25:14 5: SMA_TriPower10 -> modulstate normal
2016.11.29 09:25:14 5: SMA_TriPower10 -> opertime_start 29.11.2016 07:33:46
2016.11.29 09:25:14 5: SMA_TriPower10 -> opertime_stop 29.11.2016 17:05:42
2016.11.29 09:25:14 5: SMA_TriPower10 -> avg_power_lastminutes_05 2187
2016.11.29 09:25:14 5: SMA_TriPower10 -> avg_power_lastminutes_10 2158
2016.11.29 09:25:14 5: SMA_TriPower10 -> avg_power_lastminutes_15 2146
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_ETOTAL 13405577
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_ETODAY 1347
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_PDC1 1240
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_PDC2 1061
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_PAC1 769
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_PAC2 768
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_PAC3 772
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_PACTOT 2309
2016.11.29 09:25:14 5: SMA_TriPower10 -> state 2309
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_UDC1 539.78
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_UDC2 658.63
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_IDC1 2.299
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_IDC2 1.612
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_UAC1 234.91
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_UAC2 234.13
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_UAC3 233.61
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_IAC1 3.273
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_IAC2 3.281
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_IAC3 3.308
2016.11.29 09:25:14 5: SMA_TriPower10 -> SUSyID 181
2016.11.29 09:25:14 5: SMA_TriPower10 -> Serialnumber 123456789
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_FREQ 49.97
2016.11.29 09:25:14 5: SMA_TriPower10 -> INV_TYPE STP 10000TL-20
2016.11.29 09:25:14 5: SMA_TriPower10 -> INV_CLASS Solar Inverters
2016.11.29 09:25:14 5: SMA_TriPower10 -> INV_PACMAX1 10000
2016.11.29 09:25:14 5: SMA_TriPower10 -> INV_PACMAX2 10000
2016.11.29 09:25:14 5: SMA_TriPower10 -> INV_PACMAX3 10000
2016.11.29 09:25:14 5: SMA_TriPower10 -> INV_TEMP 45.46
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_FEEDTM 5530.2
2016.11.29 09:25:14 5: SMA_TriPower10 -> SPOT_OPERTM 5652.55
2016.11.29 09:25:14 5: SMA_TriPower10 -> INV_GRIDRELAY Closed
2016.11.29 09:25:14 5: SMA_TriPower10 -> INV_STATUS Ok
2016.11.29 09:25:14 4: SMAInverter SMA_TriPower10 -> BlockingCall getstatus_DoParse finished
2016.11.29 09:25:15 4: SMAInverter SMA_TriPower10 -> Start BlockingCall getstatus_ParseDone
2016.11.29 09:25:15 5: SMA_TriPower10 -> row_array after decoding:
2016.11.29 09:25:15 5: SMA_TriPower10 -> modulstate normal
2016.11.29 09:25:15 5: SMA_TriPower10 -> opertime_start 29.11.2016 07:33:46
2016.11.29 09:25:15 5: SMA_TriPower10 -> opertime_stop 29.11.2016 17:05:42
2016.11.29 09:25:15 5: SMA_TriPower10 -> avg_power_lastminutes_05 2187
2016.11.29 09:25:15 5: SMA_TriPower10 -> avg_power_lastminutes_10 2158
2016.11.29 09:25:15 5: SMA_TriPower10 -> avg_power_lastminutes_15 2146
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_ETOTAL 13405577
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_ETODAY 1347
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_PDC1 1240
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_PDC2 1061
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_PAC1 769
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_PAC2 768
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_PAC3 772
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_PACTOT 2309
2016.11.29 09:25:15 5: SMA_TriPower10 -> state 2309
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_UDC1 539.78
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_UDC2 658.63
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_IDC1 2.299
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_IDC2 1.612
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_UAC1 234.91
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_UAC2 234.13
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_UAC3 233.61
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_IAC1 3.273
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_IAC2 3.281
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_IAC3 3.308
2016.11.29 09:25:15 5: SMA_TriPower10 -> SUSyID 181
2016.11.29 09:25:15 5: SMA_TriPower10 -> Serialnumber 123456789
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_FREQ 49.97
2016.11.29 09:25:15 5: SMA_TriPower10 -> INV_TYPE STP 10000TL-20
2016.11.29 09:25:15 5: SMA_TriPower10 -> INV_CLASS Solar Inverters
2016.11.29 09:25:15 5: SMA_TriPower10 -> INV_PACMAX1 10000
2016.11.29 09:25:15 5: SMA_TriPower10 -> INV_PACMAX2 10000
2016.11.29 09:25:15 5: SMA_TriPower10 -> INV_PACMAX3 10000
2016.11.29 09:25:15 5: SMA_TriPower10 -> INV_TEMP 45.46
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_FEEDTM 5530.2
2016.11.29 09:25:15 5: SMA_TriPower10 -> SPOT_OPERTM 5652.55
2016.11.29 09:25:15 5: SMA_TriPower10 -> INV_GRIDRELAY Closed
2016.11.29 09:25:15 5: SMA_TriPower10 -> INV_STATUS Ok
2016.11.29 09:25:15 1: PERL WARNING: ^* matches null string many times in regex; marked by <-- HERE in m/^* <-- HERE $/ at fhem.pl line 4142.
2016.11.29 09:25:15 4: SMAInverter SMA_TriPower10 -> BlockingCall getstatus_ParseDone finished

Dabei fällt mir noch folgende Zeile auf, die ich hin und wieder im Log habe und vermutlich im Zusammenhang mit 76_SMAInverter steht.
Jemand eine Idee?

Code Auswählen


2016.11.29 09:25:15 1: PERL WARNING: ^* matches null string many times in regex; marked by <-- HERE in m/^* <-- HERE $/ at fhem.pl line 4142.

Sonnige Grüße aus Bochum,

Marcel

DS_Starter · 29 November 2016, 09:53:09

Morgen Marcel,

reload ist nicht in jedem Fall ausreichend. Je nachdem was geändert ist ein restart nötig. Also besser immer restarten wenn man sich nicht sicher ist.

Bei mir sieht es auch sehr gut aus. Beim Restart habe ich auch manchmal noch

Code Auswählen


2016.11.29 09:27:54.927 1: PERL WARNING: Use of uninitialized value $inv_susyid in concatenation (.) or string at ./FHEM/76_SMAInverter.pm line 700.
2016.11.29 09:27:54.927 1: PERL WARNING: Use of uninitialized value $inv_serial in concatenation (.) or string at ./FHEM/76_SMAInverter.pm line 700.
2016.11.29 09:27:54.927 1: PERL WARNING: Use of uninitialized value $inv_serial in concatenation (.) or string at ./FHEM/76_SMAInverter.pm line 701.
2016.11.29 09:27:54.927 1: PERL WARNING: Use of uninitialized value $inv_serial in concatenation (.) or string at ./FHEM/76_SMAInverter.pm line 702.

Ich schaue mir nochmal die Abläufe beim FHEM-Start an denn es passiert (wenn) nur bei Restart von FHEM.

EDIT: die Perl-Warnung

ZitatPERL WARNING: ^* matches null string many times in regex; marked by <-- HERE in m/^* <-- HERE $/ at fhem.pl line 4142.

gibt es bei mir nicht ! Hast du FHEM komplett aktuell ?

Grüße
Heiko

Waldmensch · 29 November 2016, 10:19:13

Das mit den Fehlern beim reload <Modul> sieht man sehr häufig. Ich habe nirgendwo eine Info gefunden, wie man das als Modulentwickler umschiffen kann. Ich bin dazu übergegangen, nur noch mit shutdown restart zu arbeiten.

Gesendet von iPhone mit Tapatalk

Xguide · 29 November 2016, 10:47:38

Ok, das habe ich verstanden! Mache ich eigentlich auch immer, nur gestern habe ich das Modul zwischen Tür und Angel eingespielt, dabei die Kinder ins Bett gebracht und siehe da...
Ich wollte nur darauf hinweisen, falls jemand genau so dusselig ist wie ich

Ich bekomme hin und wieder Timeout Meldungen im Log, hat jemand einen Ansatz wonach ich gucken kann?

Code Auswählen

2016.11.29 10:43:31 1: Timeout for getstatus_DoParse reached, terminated process 9235
2016.11.29 10:43:31 1: Timeout for getstatus_DoParse reached, terminated process 9236

DS_Starter · 29 November 2016, 10:53:08

Hi Marcel,

benutz das Attribut "timeout". Es definiert die Zeit die dem Hintergrundprozess Blockingcall eingeräumt wird den WR abzufragen.
Das timeout dient dazu Endlosläufe des Prozesses zu verhindern.

Waldmensch · 29 November 2016, 11:00:54

Da wirst du eine laggy Netzwerkverbindung haben. Ich hatte sowohl mit WLAN als auch mit Powerlan solche Probleme. Irgendwann habe ich mir dann mal einen Ring CAT5 Kabel gekauft und durchs ganze Gehöft gezogen. Der Wechselrichter hängt nun über einen Switch direkt am Router. Der FHEM Raspi hängt auch per Kabel an Router. Seitdem nie wieder ein Timeout vom WR. Selbst als ich 2 Module gleichzeitig laufen hatte, die sich heftig beharkt haben (also parallele Anfragen an den WR)

Gesendet von iPhone mit Tapatalk

DS_Starter · 29 November 2016, 11:46:22

So, jetzt aber ...
Habe noch eine "Nachlässigkeit" gefunden. Drei Array-Elemente hatten sich außerhalb der für sie bestimmten Schleife befunden um deren Existenz abzufragen.
Gehe davon aus dass die V2.6.1 auch bei euch die Warnungen eliminiert.

Schaut mal ...

Grüße
Heiko

Xguide · 29 November 2016, 12:42:28

Hallo,

2.6.1 ist drin, nach dem "shutdown restart" erstmal keine Fehler im log.

Ist 25 Sekunden ein plausibler Wert für den timeout?
Die Werte vom EnergyMeter werden alle 30 Sek. abgerufen und der Batterie WR und PV-WR werden entsprechend über get-data getriggert.
Bei mir ist alles via Kabel verbunden, an der Verbindung sollte es eigentlich nicht liegen.

DS_Starter · 29 November 2016, 12:54:31

Das ist schon ok.

Ihr müßt auch wissen dass ein Timeout kommt wenn der vorherige Lauf noich nicht abgeschlossen ist und eine neue Abfrage startet. Dann wird der Blockingcall gekillt damit nicht 2 laufen, was sich auch in einer timeout-Medlung manifestiert.

Waldmensch · 29 November 2016, 13:22:24

Ich weiß nicht ob das möglich ist, aber könnte man in diesem Fall den Status "interrupted", "killed " o.ä. ausgeben?

Gesendet von iPhone mit Tapatalk

DS_Starter · 29 November 2016, 13:34:38

Geht leider nicht. Kann ich nicht unterscheiden warum Blocking.pm die Abbruchroutine aufruft. Wird durch BlockingKill gesteuert.

DS_Starter · 29 November 2016, 14:06:54

Naja, es wird aber jetzt schon mit verbose 3 ein Logeintrag erzeugt wenn ich explizit einen Vorläufer kille.
Sieht so aus:

Code Auswählen

 Log3 ($name, 3, "SMAInverter $name - WARNING - old process $hash->{HELPER}{RUNNING_PID}{pid} will be killed now to start a new BlockingCall");

das hilft schonmal Ursachen zu ergründen. Verbose = 3 !

DS_Starter · 01 Dezember 2016, 18:29:32

Hallo miteinander,

nur zur Info ... ich habe in dem SMAEM-Thread:

https://forum.fhem.de/index.php/topic,51569.msg531907.html#msg531907

eine neue, auf non-blocking umgestellte Version von 77_SMAEM, zur Verfügung gestellt. Die Ergebnisse mit apptime sind ähnlich positiv wie bei diesem Modul.

Wer also den SMA Energymeter auch im Einsatz hat, ist eingeladen das Modul auch zu testen.
Ich würde mich freuen.

Grüße
Heiko

Xguide · 02 Dezember 2016, 14:27:39

Hallo zusammen,

ich komme noch mal auf die timeout Problematik bei mir zurück.
Wie ihr vermutlich wisst, habe ich eine Instanz vom 77_SMAEM und 2 Instanzen 76_SMAInverter laufen.
Bis heute habe ich die Werte des EnergyMeter in einem 30s Zyklus abgepollt. Durch das Aktualisieren der Readings des EM wurden die zwei Instanzen 76_SMAInverter, Beide im manual mode, via "get data" angestoßen.
Dabei kam es relativ häufig vor, dass ein timeout auftrat. Den Parameter timeout hatte ich überigens auf 25s gesetzt.

Nun habe ich mal den Zyklus auf 45s mit einem timeout von 40s gesetzt. Unregelmäßig, jedoch deutlich seltener, bekomme ich nun immer noch timeouts.
Waldmensch hat ja die These einer schlechten Netzwerkverbindung aufgestellt, die ich eigentlich nicht bestätigen kann, da alles via Kabel verbunden ist und quasi nebeneinander steht. FHEM läuft bei mir auf einem Raspberry Pi 3, der sich ziemlich langweilt. Habt ihr noch mal einen Anstoß wie ich weiter prüfen kann woran es liegt und ob alles tutti ist?

Danke und viele Güße,

Marcel

DS_Starter · 02 Dezember 2016, 15:46:59

Hallo Marcel,

eine kurze Erläuterung wie das Modul jetzt non-blocking funktioniert.
Die wesentliche Eigenschaft ist, dass nun alle Abfragen die tendenziell dazu führen dass FHEM auf eine Antwort wartet und somit blockiert, in den BlockingCall-Prozess ausgelagert wurden.
D.h. sobald eine Abfrage erfolgen soll, werden ein paar Entscheidungen analysiert ob disabled, manuell oder automatic-Mode, eingestelltes Intervall abgelaufen usw. und dann die Abfrage des Inverters an den Hintergrundprozess übergeben. Dann läuft die FHEM-Hauptschleife weiter und kann sich mit anderen Dingen beschäftigen.

Wenn nun der WR nicht innerhalb des eingestellten timeouts alle benötigten Antworten an den Hntergrundprozess schickt, kommt es zum timeout. Die Gründe können vielfältig sein, z.B. wie Waldmensch angemerkt hat schleche LAN-Verbindung. Es kann aber auch sein, dass der WR per se nicht so schnell antworten kann weil seine Webservice-Performance es nicht hergibt, auch zum Beispiel durch Parallelabfagen (es gibt auch noch dem Sunny Homemanager) oder durch weitere Testinstanz(en).

Das "normale" Modul würde in dieser Zeit blockieren bzw. in ein freeze laufen, das non-blocking Modul bekommt ein timeout.

Ein weiterer Fall wäre dass der Hintergrundprozess noch läuft, aber schon die nächste Abfagre getriggert wird. Dann kommt es ebenfalls zum Timeout (durch BlockingKill). Allerdings gebe ich im Log dann aus:

Code Auswählen

SMAInverter $name - WARNING - old process <PID> will be killed now to start a new BlockingCall

Um die Analyse in solchen Fällen zu unterstützen, könnte ich das Modul mit ein/zwei Readings ergänzen die man hinzuschalten kann und die die im Hintergrund verbrauchte Zeit messen und ausgeben. Mache ich bei meinem 93_DbRep so.
Das funktioniert aber nur wenn man kein timeout bekommt. Braucht man auch nicht ... wenn ein timeout kommt weiß man ja wieviel Zeit verbraucht wurde

Man sieht allerdings mit Hilfe dieser Readings wo man im Durchschnitt mit den Antwortzeiten des WR liegt. Das könnte hilfreich sein und die Analyse erleichtern.

Grüße
Heiko