FHEM Absturz im Zusammenhang mit mysensors

Begonnen von schka17, 29 Juli 2016, 10:08:12

Vorheriges Thema - Nächstes Thema

schka17

Hallo,

habe seit einigen das Problem dass mein fhem prozess beendet wird, und zwar unmittelbar nachdem ein Fehler scheinbar bei 00_MYSENSORS.pm auftaucht, so sieht das im Log aus:

2016.07.29 03:30:13 5: MYSENSORS/RAW: /100;1;1;0;0;26.0

2016.07.29 03:30:13 5: MYSENSORS Read: Rx: fr=100 ci=001 c=001(C_SET         ) st=000(V_TEMP          ) ack=0 '26.0'

2016.07.29 03:30:13 3: MYSENSORS: ignoring set-msg from unknown radioId 100, childId 1 for V_TEMP
[Fri Jul 29 03:30:13 2016] fhem.pl: Use of uninitialized value $cmd in sprintf at FHEM/lib/Device/MySensors/Message.
pm line 42.
[Fri Jul 29 03:30:13 2016] fhem.pl: Use of uninitialized value $cmd in sprintf at FHEM/lib/Device/MySensors/Message.
pm line 42.
2016.07.29 03:30:14 5: MYSENSORS/RAW: /102;255;0;0;18;1.5

2016.07.29 03:30:14 5: MYSENSORS Read: Rx: fr=102 ci=255 c=000(C_PRESENTATION) st=018(S_ARDUINO_REPEATER_NODE) ack=0
'1.5'

2016.07.29 03:30:14 5: MYSENSORS/RAW: /102;255;3;0;6;0

2016.07.29 03:30:14 5: MYSENSORS Read: Rx: fr=102 ci=255 c=003(C_INTERNAL    ) st=006(I_CONFIG        ) ack=0 '0'

2016.07.29 03:30:14 5: MYSENSORS send: Rx: fr=102 ci=255 c=003(C_INTERNAL    ) st=006(I_CONFIG        ) ack=1 'M'

2016.07.29 03:30:14 5: SW: 3130323b3235353b333b313b363b4d0a
<h1>Software error:</h1>
<pre>Can't use an undefined value as an ARRAY reference at ./FHEM/00_MYSENSORS.pm line 447.
</pre>
<p>
For help, please send mail to this site's webmaster, giving this error message
and the time and date of the error.

</p>
[Fri Jul 29 03:30:14 2016] fhem.pl: Can't use an undefined value as an ARRAY reference at ./FHEM/00_MYSENSORS.pm lin
e 447.
2016.07.29 03:30:20 3: [UtilsHourCounter] Init Done with Version 1.0.1.0 - 10.12.2014 (john)
[Fri Jul 29 03:30:21 2016] fhem.pl: Subroutine myUtils_Initialize redefined at ./FHEM/99_myUtils.pm line 109.
2016.07.29 03:30:21 1: Including fhem.cfg


Versionen:

Latest Revision: 11854

File                   Rev   Last Change

fhem.pl                11756 2016-07-07 12:43:00Z rudolfkoenig
96_allowed.pm          11391 2016-05-05 11:16:05Z rudolfkoenig
74_AMAD.pm             11853 2016-07-25 19:41:49Z markooldenburg
90_at.pm               11342 2016-04-29 11:25:51Z rudolfkoenig
98_autocreate.pm       11094 2016-03-20 09:41:03Z rudolfkoenig
57_Calendar.pm         11136 2016-03-28 14:02:04Z borisneubert
98_cloneDummy.pm        5919 2014-05-21 05:03:48Z joachim09876
00_CUL.pm              11637 2016-06-09 18:01:20Z rudolfkoenig
15_CUL_EM.pm           11348 2016-04-30 13:41:18Z rudolfkoenig
10_CUL_HM.pm           11845 2016-07-24 14:56:44Z martinp876
10_CUL_IR.pm            3580 2013-08-02 16:17:38Z betateilchen
No Id found for 14_CUL_REDIRECT.pm
14_CUL_TCM97001.pm     11294 2016-04-21 20:32:28Z dancer0705
14_CUL_TX.pm           10140 2015-12-09 15:58:41Z rudolfkoenig
14_CUL_WS.pm           10794 2016-02-11 06:26:06Z rudolfkoenig
# $Id: 98_DeviceMonitor.pm  $

# $Id: 39_DLCD.pm 1122 2014-10-03 11:44:00Z Florian Duesterwald $

98_DOIF.pm             11314 2016-04-26 18:29:24Z damian-s
98_dummy.pm            11442 2016-05-15 12:43:12Z rudolfkoenig
36_EC3000.pm           10297 2015-12-29 19:04:09Z justme1968
70_ENIGMA2.pm          11774 2016-07-09 14:04:45Z loredo
91_eventTypes.pm       10530 2016-01-16 19:27:21Z rudolfkoenig
93_FHEM2FHEM.pm        11732 2016-07-02 17:41:31Z rudolfkoenig
98_fheminfo.pm         10570 2016-01-19 06:39:23Z rudolfkoenig
01_FHEMWEB.pm          11789 2016-07-12 13:44:14Z rudolfkoenig
11_FHT.pm              10204 2015-12-19 16:07:11Z rudolfkoenig
92_FileLog.pm          11336 2016-04-28 19:28:05Z rudolfkoenig
95_FLOORPLAN.pm        11443 2016-05-15 14:17:21Z ulimaass
10_FS20.pm             11432 2016-05-12 08:35:57Z rudolfkoenig
02_FTUISRV.pm          11311 2016-04-25 18:36:16Z viegener
98_GEOFANCY.pm         11842 2016-07-24 13:38:12Z loredo
# $Id: 99_getstate.pm,v 1.3 2009-12-16 16:46:00 m_fischer Exp $
20_GUEST.pm            11838 2016-07-24 12:01:58Z loredo
37_harmony.pm          11638 2016-06-09 18:14:26Z justme1968
98_HMinfo.pm           11685 2016-06-18 08:02:09Z martinp876
00_HMLAN.pm            11645 2016-06-11 09:54:00Z martinp876
12_HMS.pm               5097 2014-03-02 15:25:08Z rudolfkoenig
95_holiday.pm          10530 2016-01-16 19:27:21Z rudolfkoenig
98_HourCounter.pm      11307 2016-04-25 08:02:06Z rudolfkoenig
98_HTTPMOD.pm          11692 2016-06-19 09:08:31Z ststrobel
02_HTTPSRV.pm          10733 2016-02-06 09:02:02Z borisneubert
49_IPCAM.pm             2626 2013-02-01 19:19:15Z mfr69bs
36_JeeLink.pm          10927 2016-02-24 09:48:16Z justme1968
36_KeyValueProtocol.pm  9976 2015-11-22 17:46:25Z hcs-svn
13_KS300.pm            11620 2016-06-05 16:29:46Z rudolfkoenig
36_LaCrosse.pm         10303 2015-12-29 22:07:01Z hcs-svn
31_LightScene.pm       11642 2016-06-10 13:10:25Z justme1968
98_logProxy.pm         11533 2016-05-27 17:58:39Z justme1968
00_MQTT.pm             10418 2016-01-08 23:28:27Z ntruchsess
10_MQTT_BRIDGE.pm       6935 2014-11-09 20:35:34Z ntruchsess
10_MQTT_DEVICE.pm       6935 2014-11-09 20:35:34Z ntruchsess
No Id found for 99_myPoolcontroller.pm
00_MYSENSORS.pm         9341 2015-10-02 14:55:54Z ntruchsess
10_MYSENSORS_DEVICE.pm 10967 2016-02-29 20:37:40Z ntruchsess
# $Id: 99_myUtils.pm $
91_notify.pm           11178 2016-04-03 14:20:32Z rudolfkoenig
34_NUT.pm               9023 2015-08-05 09:00:12Z narsskrarc
11_OWDevice.pm         11360 2016-05-01 13:11:21Z borisneubert
10_OWServer.pm         11137 2016-03-28 14:07:20Z borisneubert
36_PCA301.pm           11279 2016-04-18 17:58:15Z justme1968
73_PRESENCE.pm         11810 2016-07-17 18:17:46Z markusbloch
70_PushNotifier.pm     11040 2016-03-10 14:42:46Z xusader
98_rain.pm              6916 2014-11-08 11:28:26Z baumrasen
33_readingsGroup.pm    11573 2016-05-31 06:54:52Z justme1968
33_readingsProxy.pm    11379 2016-05-03 14:08:47Z justme1968
95_remotecontrol.pm    10724 2016-02-04 18:17:33Z ulimaass
10_RESIDENTS.pm        11838 2016-07-24 12:01:58Z loredo
20_ROOMMATE.pm         11838 2016-07-24 12:01:58Z loredo
02_RSS.pm               7991 2015-02-15 11:50:48Z borisneubert
92_rsyslog.pm          11101 2016-03-20 15:00:59Z betateilchen
No Id found for 42_SMARTMON.pm
10_SOMFY.pm            11433 2016-05-12 19:15:18Z viegener
32_speedtest.pm        11378 2016-05-03 12:34:13Z justme1968
98_statistics.pm       11309 2016-04-25 17:07:12Z grompo
98_structure.pm        10530 2016-01-16 19:27:21Z rudolfkoenig
99_SUNRISE_EL.pm       10569 2016-01-19 06:30:28Z rudolfkoenig
98_SVG.pm              11771 2016-07-09 11:11:48Z rudolfkoenig
42_SYSMON.pm           11105 2016-03-20 19:37:02Z hexenmeister
50_TelegramBot.pm      11714 2016-06-25 14:45:00Z viegener
98_telnet.pm           11789 2016-07-12 13:44:14Z rudolfkoenig
98_THRESHOLD.pm         8521 2015-05-03 18:32:17Z damian-s
45_TRX.pm              11456 2016-05-15 20:19:24Z wherzig
46_TRX_ELSE.pm         11451 2016-05-15 19:04:06Z wherzig
46_TRX_LIGHT.pm        11592 2016-06-01 21:15:30Z wherzig
46_TRX_SECURITY.pm     11452 2016-05-15 19:05:17Z wherzig
46_TRX_WEATHER.pm      11450 2016-05-15 19:03:23Z wherzig
59_Twilight.pm         11824 2016-07-20 18:30:59Z dietmar63
99_Utils.pm            10570 2016-01-19 06:39:23Z rudolfkoenig
# $Id: 99_UtilsHourCounter.pm 2014-12-16 20:15:33 john $
77_UWZ.pm              11852 2016-07-25 19:25:01Z markooldenburg
# $Id: 39_VALVES.pm 1015 2014-10-22 04:35:00Z Florian Duesterwald $

98_version.pm          11026 2016-03-08 07:25:45Z markusbloch
91_watchdog.pm         11173 2016-04-02 15:20:48Z rudolfkoenig
59_Weather.pm          11249 2016-04-16 17:03:47Z borisneubert
98_weblink.pm          10530 2016-01-16 19:27:21Z rudolfkoenig
No Id found for 95_WebViewControl.pm
98_WeekdayTimer.pm     11504 2016-05-22 20:01:08Z dietmar63
32_WifiLight.pm        10404 2016-01-07 21:39:44Z herrmannj
98_WOL.pm              10595 2016-01-22 17:05:38Z dietmar63
70_XBMC.pm             10966 2016-02-29 19:58:19Z vbs2

Blocking.pm            11465 2016-05-16 11:56:10Z rudolfkoenig
Color.pm               11159 2016-03-30 16:08:06Z justme1968
No Id found for Constants.pm
DevIo.pm               11786 2016-07-12 08:44:10Z rudolfkoenig
GPUtils.pm              6653 2014-10-02 11:59:37Z ntruchsess
HMConfig.pm            11845 2016-07-24 14:56:44Z martinp876
No Id found for HMConfig_SenTHPL.pm
HttpUtils.pm           11758 2016-07-07 14:09:30Z rudolfkoenig
No Id found for Message.pm
# $Id: OWNet.pm,v 1.24 2013/02/02 11:41:28 alfille Exp $
RESIDENTStk.pm         11838 2016-07-24 12:01:58Z loredo
RTypes.pm              10476 2016-01-12 21:03:33Z borisneubert
SetExtensions.pm       11513 2016-05-24 17:56:05Z rudolfkoenig
TcpServerUtils.pm      11758 2016-07-07 14:09:30Z rudolfkoenig
YahooWeatherAPI.pm     11249 2016-04-16 17:03:47Z borisneubert


This is perl 5, version 20, subversion 2 (v5.20.2) built for x86_64-linux-gnu-thread-multi
Linux HAL9000 3.2.0-4-amd64 #1 SMP Debian 3.2.78-1 x86_64 GNU/Linux

das mysensors Gateway ist auf einer NodeMCU mit der Beta-1.6 und läuft eigentlich seit mehreren Monaten ohne Probleme

Internals:
   DEF        192.168.255.16:5003
   DeviceName 192.168.255.16:5003
   FD         195
   NAME       MySensors_GW_2
   NOTIFYDEV  global
   NR         2591
   NTFY_ORDER 50-MySensors_GW_2
   PARTIAL
   STATE      startup complete
   TYPE       MYSENSORS
   ack        1
   inclusion-mode 1
   outstandingAck 0
   version    1.6.0-beta
   Readings:
     2016-07-29 03:31:26   connection      startup complete
     2016-07-29 03:31:18   state           opened
   Messagesforradioid:
     102:
       lastseen   -1
       nexttry    -1
       numtries   1
       messages:
Attributes:
   autocreate 1
   comment    /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_A504814J-if00-port0@115200
192.168.255.16:5003
   devStateIcon startup.*:it_router@green .*:it_router@red
   group      Transceiver
   icon       it_router
   requestAck 1
   room       System
   stateFormat connection
   verbose    5

das zweite Gateway ist eine Platine von Hexenmeister mit der 2.0 Beta, hat eigentlich auch die letzen drei Wochen ohne Problem funktioniert
Internals:
   DEF        192.168.255.18:5003
   DeviceName 192.168.255.18:5003
   FD         194
   NAME       MySensors_GW
   NOTIFYDEV  global
   NR         2426
   NTFY_ORDER 50-MySensors_GW
   PARTIAL
   STATE      startup complete
   TYPE       MYSENSORS
   ack        1
   inclusion-mode 1
   outstandingAck 0
   version    2.0.0-beta
   Readings:
     2016-07-29 05:55:30   connection      startup complete
     2016-07-29 05:55:30   state           opened
   Messagesforradioid:
     0:
       lastseen   -1
       nexttry    -1
       numtries   1
       messages:
     100:
       lastseen   -1
       nexttry    -1
       numtries   1
       messages:
     101:
       lastseen   -1
       nexttry    -1
       numtries   1
       messages:
     5:
       lastseen   -1
       nexttry    -1
       numtries   1
       messages:
Attributes:
   autocreate 1
   comment    /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_A504814J-if00-port0@115200
192.168.255.16:5003
   devStateIcon startup.*:it_router@green .*:it_router@red
   group      Transceiver
   icon       it_router
   requestAck 1
   room       System
   stateFormat connection
   verbose    0


Dank Betateilchen's Beitrag für die Konfiguration von systemd (man lernt doch nie aus...) wird fhem zwar wieder gestartet und es gibt jetzt keinen Totalausfall wie beim ersten Ausfall am Dienstag, da war das noch nicht konfiguriert und ich war natürlich auf Dienstreise, meine Frau war etwas unentspannt und der Wintergarten etwas überhitzt.....

Hat jemand eine Idee wo ich den Fehler suchen kann?

danke und Gruß aus dem verregneten Österreich

Karl
M: Thinclient x64 Debian | CUL FS20, HMS100WD, HMS100TF, HMS100T, HMS100CO, S300, S555TH | OWServer DS1420, DS18B20, DS2408 | RFXCOM UVN128, THWR800, THGR228N,RTGR328, PCR800 |Jeelink PCA301 EC3000|CUNO+IR|HMLAN|HMUSB|CUL433 Somfy|mySensors|espEasy
S1:Raspberry mit BPM810, Jeelink EC3000