[gelöst] SelbstbauCul Problem und Fragen

Begonnen von Mundus, 01 März 2018, 00:57:45

Vorheriges Thema - Nächstes Thema

Mundus

Hi,

ich habe einen SelbstbauCul gebastelt und wollte diesen nun nutzen. Leider taucht dabei ein Problem auf, bei dem ich keine Lösung habe. Nun wird es wahrscheinlich kompliziert -zumindest habe ich den Eindruck, wenn ich das Geschriebene betrachte-.

Problem:
Der Cul steigt teilweise aus. Leider kann ich dies nicht anhand von Protokollen (wobei ich nicht weiß, ob ich die richtigen Protokolle öffne) nachvollziehen. Wenn ich einen kompletten Neustart des PI (mit FHEM und SelbstbauCul) durchführe, erkennt er den Cul nicht und FHEM startet nicht bzw. nicht korrekt. Protokoll:
2018.02.28 23:57:14 5: Initializing Type Library:
2018.02.28 23:57:14 1: Including fhem.cfg
2018.02.28 23:57:14 5: Cmd: >attr global userattr cmdIcon devStateIcon devStateStyle icon sortby webCmd widgetOverride<
2018.02.28 23:57:14 5: Cmd: >attr global autoload_undefined_devices 1<
2018.02.28 23:57:14 5: Cmd: >attr global logfile ./log/fhem-%Y-%m.log<
2018.02.28 23:57:14 5: Cmd: >attr global modpath .<
2018.02.28 23:57:14 5: Cmd: >attr global motd SecurityCheck:

WEB,WEBphone,WEBtablet has no associated allowed device with basicAuth.
telnetPort has no associated allowed device with password/globalpassword.

Restart FHEM for a new check if the problem is fixed,
or set the global attribute motd to none to supress this message.
<
2018.02.28 23:57:14 5: Cmd: >attr global statefile ./log/fhem.save<
2018.02.28 23:57:14 5: Cmd: >attr global updateInBackground 1<
2018.02.28 23:57:14 5: Cmd: >attr global verbose 5<
2018.02.28 23:57:14 5: Cmd: >define telnetPort telnet 7072 global<
2018.02.28 23:57:14 5: Loading ./FHEM/98_telnet.pm
2018.02.28 23:57:14 3: telnetPort: port 7072 opened
2018.02.28 23:57:14 5: Cmd: >define Logfile FileLog ./log/fhem-%Y-%m.log fakelog<
2018.02.28 23:57:14 5: Loading ./FHEM/92_FileLog.pm
2018.02.28 23:57:14 5: Cmd: >define autocreate autocreate<
2018.02.28 23:57:14 5: Loading ./FHEM/98_autocreate.pm
2018.02.28 23:57:14 5: Cmd: >attr autocreate filelog ./log/%NAME-%Y.log<
2018.02.28 23:57:14 5: Cmd: >define eventTypes eventTypes ./log/eventTypes.txt<
2018.02.28 23:57:14 5: Loading ./FHEM/91_eventTypes.pm
2018.02.28 23:57:14 2: eventTypes: loaded 11 events from ./log/eventTypes.txt
2018.02.28 23:57:14 5: Cmd: >define initialUsbCheck notify global:INITIALIZED usb create<
2018.02.28 23:57:14 5: Loading ./FHEM/91_notify.pm
2018.02.28 23:57:14 5: Cmd: >define WEB FHEMWEB 8083 global<
2018.02.28 23:57:14 5: Loading ./FHEM/01_FHEMWEB.pm
2018.02.28 23:57:16 3: WEB: port 8083 opened
2018.02.28 23:57:16 5: Cmd: >define WEBphone FHEMWEB 8084 global<
2018.02.28 23:57:16 3: WEBphone: port 8084 opened
2018.02.28 23:57:16 5: Cmd: >attr WEBphone stylesheetPrefix smallscreen<
2018.02.28 23:57:16 5: Cmd: >define WEBtablet FHEMWEB 8085 global<
2018.02.28 23:57:16 3: WEBtablet: port 8085 opened
2018.02.28 23:57:16 5: Cmd: >attr WEBtablet stylesheetPrefix touchpad<
2018.02.28 23:57:16 5: Cmd: >define meinCul CUL /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_A505MQF0-if00-port0@38400 1234<
2018.02.28 23:57:16 5: Loading ./FHEM/00_CUL.pm
2018.02.28 23:57:16 3: Opening meinCul device /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_A505MQF0-if00-port0
2018.02.28 23:57:17 3: Setting meinCul serial parameters to 38400,8,N,1
2018.02.28 23:57:17 5: SW: V
2018.02.28 23:57:20 5: SW: V
2018.02.28 23:57:20 5: CUL/RAW (ReadAnswer): V 1.26.02 a-cul
2018.02.28 23:57:20 5: CUL/RAW (ReadAnswer): fw Build: private build (unknown) nanoCUL433 (F-Band: 433M
2018.02.28 23:57:20 5: CUL/RAW (ReadAnswer): Hz)

2018.02.28 23:57:20 5: SW: ?
2018.02.28 23:57:20 5: CUL/RAW (ReadAnswer): ? (? is unknown) Use one of A B C e F f G i
2018.02.28 23:57:20 5: CUL/RAW (ReadAnswer):  K L l M N R T t U V W X x

2018.02.28 23:57:20 3: meinCul: Possible commands: ABCeFfGiKLlMNRTtUVWXx
2018.02.28 23:57:20 5: SW: X21
2018.02.28 23:57:20 5: SW: T01
2018.02.28 23:57:20 5: CUL/RAW (ReadAnswer): 1234

2018.02.28 23:57:20 5: GOT CUL fhtid: 1234
2018.02.28 23:57:20 3: meinCul device opened
2018.02.28 23:57:20 5: Cmd: >attr meinCul room Cul<
2018.02.28 23:57:20 5: Cmd: >attr meinCul verbose 5<
2018.02.28 23:57:20 5: Cmd: >define FileLog_cul_Wetter_Sensor_aussen FileLog ./log/cul_Wetter_Sensor_aussen-%Y.log cul_Wetter_Sensor_aussen<
2018.02.28 23:57:20 5: Cmd: >attr FileLog_cul_Wetter_Sensor_aussen logtype text<
2018.02.28 23:57:20 5: Cmd: >define FileLog_Cul FileLog ./log/Cul-%Y-%m-%d.log meinCul<
2018.02.28 23:57:20 5: Cmd: >attr FileLog_Cul logtype text<
2018.02.28 23:57:20 5: Cmd: >attr FileLog_Cul room Cul<
2018.02.28 23:57:20 1: Including ./log/fhem.save
2018.02.28 23:57:20 5: Cmd: >setstate FileLog_Cul active<
2018.02.28 23:57:20 5: Cmd: >setstate FileLog_Cul 2018-02-28 23:57:00 linesInTheFile 5<
2018.02.28 23:57:20 5: Cmd: >setstate FileLog_cul_Wetter_Sensor_aussen active<
2018.02.28 23:57:20 5: Cmd: >setstate FileLog_cul_Wetter_Sensor_aussen 2018-02-24 20:08:11 linesInTheFile 320<
2018.02.28 23:57:20 5: Cmd: >setstate Logfile active<
2018.02.28 23:57:20 5: Cmd: >setstate autocreate active<
2018.02.28 23:57:20 5: Cmd: >setstate eventTypes active<
2018.02.28 23:57:20 5: Cmd: >setstate global no definition<
2018.02.28 23:57:20 5: Cmd: >setstate initialUsbCheck 2018-02-28 22:31:10<
2018.02.28 23:57:20 5: Cmd: >setstate initialUsbCheck 2018-02-28 22:31:04 state active<
2018.02.28 23:57:20 5: Cmd: >setstate meinCul 2018-02-28 23:57:00 cmds  A B C e F f G i K L l M N R T t U V W X x<
2018.02.28 23:57:20 5: Cmd: >setstate meinCul 2018-02-28 22:47:38 raw No answer<
2018.02.28 23:57:20 5: Cmd: >setstate meinCul 2018-02-28 23:57:00 state Initialized<
2018.02.28 23:57:20 5: Cmd: >setstate meinCul 2018-02-28 23:56:31 uptime No answer<
2018.02.28 23:57:20 5: Cmd: >setstate meinCul 2018-02-28 22:37:39 version V 1.26.02 a-culfw Build: private build (unknown) nanoCUL433 (F-Band: 433MHz)<
2018.02.28 23:57:20 5: Starting notify loop for global, 1 event(s), first is INITIALIZED
2018.02.28 23:57:20 5: createNotifyHash
2018.02.28 23:57:20 5: Triggering initialUsbCheck
2018.02.28 23:57:20 4: initialUsbCheck exec usb create
2018.02.28 23:57:20 5: Cmd: >usb create<
2018.02.28 23:57:20 1: usb create starting
2018.02.28 23:57:22 4: ### ttyAMA0: checking if it is a CUL
2018.02.28 23:57:22 3: Probing CUL device /dev/ttyAMA0
2018.02.28 23:57:22 5: SW: 0a
2018.02.28 23:57:22 5: SW: 560a
2018.02.28 23:57:22 4: got wrong answer for a CUL
2018.02.28 23:57:22 4: ### ttyAMA0: checking if it is a TCM_ESP3
2018.02.28 23:57:22 3: Probing TCM_ESP3 device /dev/ttyAMA0
2018.02.28 23:57:23 5: SW: 5500010005700838
2018.02.28 23:57:23 4: got wrong answer for a TCM_ESP3
2018.02.28 23:57:23 4: ### ttyAMA0: checking if it is a FRM
2018.02.28 23:57:23 3: Probing FRM device /dev/ttyAMA0
2018.02.28 23:57:23 5: SW: f9

Nach SW: f9 hängt das gesamte FHEM. Nun ziehe ich den CUL ab und stecke ihn wieder an, danach rebootet der gesamte PI (komisch) und das entsprechende folgende LOG sieht wie folgt aus 2018.03.01 00:14:33 5: Initializing Type Library:
2018.03.01 00:14:33 1: Including fhem.cfg
2018.03.01 00:14:33 5: Cmd: >attr global userattr cmdIcon devStateIcon devStateStyle icon sortby webCmd widgetOverride<
2018.03.01 00:14:33 5: Cmd: >attr global autoload_undefined_devices 1<
2018.03.01 00:14:33 5: Cmd: >attr global logfile ./log/fhem-%Y-%m.log<
2018.03.01 00:14:33 5: Cmd: >attr global modpath .<
2018.03.01 00:14:33 5: Cmd: >attr global motd SecurityCheck:

WEB,WEBphone,WEBtablet has no associated allowed device with basicAuth.
telnetPort has no associated allowed device with password/globalpassword.

Restart FHEM for a new check if the problem is fixed,
or set the global attribute motd to none to supress this message.
<
2018.03.01 00:14:33 5: Cmd: >attr global statefile ./log/fhem.save<
2018.03.01 00:14:33 5: Cmd: >attr global updateInBackground 1<
2018.03.01 00:14:33 5: Cmd: >attr global verbose 5<
2018.03.01 00:14:33 5: Cmd: >define telnetPort telnet 7072 global<
2018.03.01 00:14:33 5: Loading ./FHEM/98_telnet.pm
2018.03.01 00:14:33 3: telnetPort: port 7072 opened
2018.03.01 00:14:33 5: Cmd: >define Logfile FileLog ./log/fhem-%Y-%m.log fakelog<
2018.03.01 00:14:33 5: Loading ./FHEM/92_FileLog.pm
2018.03.01 00:14:33 5: Cmd: >define autocreate autocreate<
2018.03.01 00:14:33 5: Loading ./FHEM/98_autocreate.pm
2018.03.01 00:14:33 5: Cmd: >attr autocreate filelog ./log/%NAME-%Y.log<
2018.03.01 00:14:33 5: Cmd: >define eventTypes eventTypes ./log/eventTypes.txt<
2018.03.01 00:14:33 5: Loading ./FHEM/91_eventTypes.pm
2018.03.01 00:14:34 2: eventTypes: loaded 11 events from ./log/eventTypes.txt
2018.03.01 00:14:34 5: Cmd: >define initialUsbCheck notify global:INITIALIZED usb create<
2018.03.01 00:14:34 5: Loading ./FHEM/91_notify.pm
2018.03.01 00:14:34 5: Cmd: >define WEB FHEMWEB 8083 global<
2018.03.01 00:14:34 5: Loading ./FHEM/01_FHEMWEB.pm
2018.03.01 00:14:35 3: WEB: port 8083 opened
2018.03.01 00:14:35 5: Cmd: >define WEBphone FHEMWEB 8084 global<
2018.03.01 00:14:35 3: WEBphone: port 8084 opened
2018.03.01 00:14:35 5: Cmd: >attr WEBphone stylesheetPrefix smallscreen<
2018.03.01 00:14:35 5: Cmd: >define WEBtablet FHEMWEB 8085 global<
2018.03.01 00:14:35 3: WEBtablet: port 8085 opened
2018.03.01 00:14:35 5: Cmd: >attr WEBtablet stylesheetPrefix touchpad<
2018.03.01 00:14:35 5: Cmd: >define meinCul CUL /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_A505MQF0-if00-port0@38400 1234<
2018.03.01 00:14:35 5: Loading ./FHEM/00_CUL.pm
2018.03.01 00:14:36 3: Opening meinCul device /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_A505MQF0-if00-port0
2018.03.01 00:14:37 3: Setting meinCul serial parameters to 38400,8,N,1
2018.03.01 00:14:37 5: SW: V
2018.03.01 00:14:40 5: SW: V
2018.03.01 00:14:40 5: CUL/RAW (ReadAnswer): V 1.26.02 a-culfw Build: private
2018.03.01 00:14:40 5: CUL/RAW (ReadAnswer): build (unknown) nanoCUL433 (F-Band: 433MHz)

2018.03.01 00:14:40 5: SW: ?
2018.03.01 00:14:40 5: CUL/RAW (ReadAnswer): ? (? is unknown) Use one of A B C e F f G i
2018.03.01 00:14:40 5: CUL/RAW (ReadAnswer):  K L l M N R T t U V W X x

2018.03.01 00:14:40 3: meinCul: Possible commands: ABCeFfGiKLlMNRTtUVWXx
2018.03.01 00:14:40 5: SW: X21
2018.03.01 00:14:40 5: SW: T01
2018.03.01 00:14:40 5: CUL/RAW (ReadAnswer): 1234

2018.03.01 00:14:40 5: GOT CUL fhtid: 1234
2018.03.01 00:14:40 3: meinCul device opened
2018.03.01 00:14:40 5: Cmd: >attr meinCul room Cul<
2018.03.01 00:14:40 5: Cmd: >attr meinCul verbose 5<
2018.03.01 00:14:40 5: Cmd: >define FileLog_cul_Wetter_Sensor_aussen FileLog ./log/cul_Wetter_Sensor_aussen-%Y.log cul_Wetter_Sensor_aussen<
2018.03.01 00:14:40 5: Cmd: >attr FileLog_cul_Wetter_Sensor_aussen logtype text<
2018.03.01 00:14:40 5: Cmd: >define FileLog_Cul FileLog ./log/Cul-%Y-%m-%d.log meinCul<
2018.03.01 00:14:40 5: Cmd: >attr FileLog_Cul logtype text<
2018.03.01 00:14:40 5: Cmd: >attr FileLog_Cul room Cul<
2018.03.01 00:14:40 1: Including ./log/fhem.save
2018.03.01 00:14:40 5: Cmd: >setstate FileLog_Cul active<
2018.03.01 00:14:40 5: Cmd: >setstate FileLog_Cul 2018-02-28 23:57:00 linesInTheFile 5<
2018.03.01 00:14:40 5: Cmd: >setstate FileLog_cul_Wetter_Sensor_aussen active<
2018.03.01 00:14:40 5: Cmd: >setstate FileLog_cul_Wetter_Sensor_aussen 2018-02-24 20:08:11 linesInTheFile 320<
2018.03.01 00:14:40 5: Cmd: >setstate Logfile active<
2018.03.01 00:14:40 5: Cmd: >setstate autocreate active<
2018.03.01 00:14:40 5: Cmd: >setstate eventTypes active<
2018.03.01 00:14:40 5: Cmd: >setstate global no definition<
2018.03.01 00:14:40 5: Cmd: >setstate initialUsbCheck 2018-02-28 22:31:10<
2018.03.01 00:14:40 5: Cmd: >setstate initialUsbCheck 2018-02-28 22:31:04 state active<
2018.03.01 00:14:40 5: Cmd: >setstate meinCul 2018-02-28 23:57:00 cmds  A B C e F f G i K L l M N R T t U V W X x<
2018.03.01 00:14:40 5: Cmd: >setstate meinCul 2018-02-28 22:47:38 raw No answer<
2018.03.01 00:14:40 5: Cmd: >setstate meinCul 2018-02-28 23:57:00 state Initialized<
2018.03.01 00:14:40 5: Cmd: >setstate meinCul 2018-02-28 23:56:31 uptime No answer<
2018.03.01 00:14:40 5: Cmd: >setstate meinCul 2018-02-28 22:37:39 version V 1.26.02 a-culfw Build: private build (unknown) nanoCUL433 (F-Band: 433MHz)<
2018.03.01 00:14:40 5: Starting notify loop for global, 1 event(s), first is INITIALIZED
2018.03.01 00:14:40 5: createNotifyHash
2018.03.01 00:14:40 5: Triggering initialUsbCheck
2018.03.01 00:14:40 4: initialUsbCheck exec usb create
2018.03.01 00:14:40 5: Cmd: >usb create<
2018.03.01 00:14:40 1: usb create starting
2018.03.01 00:14:43 4: ### ttyAMA0: checking if it is a CUL
2018.03.01 00:14:43 3: Probing CUL device /dev/ttyAMA0
2018.03.01 00:14:43 3: Can't open /dev/ttyAMA0: Permission denied
2018.03.01 00:14:43 4: cannot open the device
2018.03.01 00:14:43 4: ### ttyUSB0: checking if it is a TCM_ESP3
2018.03.01 00:14:43 4: ttyUSB0 is already used by the fhem device meinCul
2018.03.01 00:14:43 1: usb create end
2018.03.01 00:14:43 5: End notify loop for global
2018.03.01 00:14:43 2: SecurityCheck:  WEB,WEBphone,WEBtablet has no associated allowed device with basicAuth. telnetPort has no associated allowed device with password/globalpassword.  Restart FHEM for a new check if the problem is fixed, or set the global attribute motd to none to supress this message.
2018.03.01 00:14:43 0: Featurelevel: 5.8
2018.03.01 00:14:43 0: Server started with 12 defined entities (fhem.pl:13447/2017-02-19 perl:5.024001 os:linux user:fhem pid:336)
2018.03.01 00:18:07 5: CUL/RAW: /s254115B842C047;  416: 7888

2018.03.01 00:18:07 4: CUL_Parse: meinCul s254115B842C047;  416: 7888
2018.03.01 00:18:07 5: meinCul: dispatch s254115B842C047;  416: 7888
2018.03.01 00:18:08 5: Loading ./FHEM/14_CUL_TCM97001.pm
2018.03.01 00:18:09 4: CUL_TCM97001 Unknown 37 (254115B842C047) length: 14 RSSI: -38.5
2018.03.01 00:18:09 2: CUL_TCM97001 Unknown device Unknown, please define it
2018.03.01 00:18:09 5: Starting notify loop for global, 1 event(s), first is UNDEFINED Unknown CUL_TCM97001 CUL_TCM97001_Unknown
2018.03.01 00:18:09 2: autocreate: define Unknown CUL_TCM97001 CUL_TCM97001_Unknown
2018.03.01 00:18:09 2: autocreate: define FileLog_Unknown FileLog ./log/Unknown-%Y.log Unknown
2018.03.01 00:18:09 5: End notify loop for global
2018.03.01 00:18:09 5: CUL/RAW: /s254115B842C049;  432: 7856
s254115B842C048;  432: 7856
s254115B842C047;  432: 7856
s254115B842C048;  416: 7872

2018.03.01 00:18:09 4: CUL_Parse: meinCul s254115B842C049;  432: 7856
2018.03.01 00:18:09 5: meinCul: dispatch s254115B842C049;  432: 7856
2018.03.01 00:18:09 4: CUL_TCM97001 Unknown 37 (254115B842C049) length: 14 RSSI: -37.5
2018.03.01 00:18:09 4: CUL_TCM97001 Device not implemented yet name Unknown msg 254115B842C049
2018.03.01 00:18:09 5: Starting notify loop for Unknown, 1 event(s), first is Code: 254115B842C0
2018.03.01 00:18:09 5: createNotifyHash
2018.03.01 00:18:09 5: End notify loop for Unknown
2018.03.01 00:18:09 4: CUL_Parse: meinCul s254115B842C048;  432: 7856
2018.03.01 00:18:09 5: meinCul: dispatch s254115B842C048;  432: 7856
2018.03.01 00:18:09 4: CUL_TCM97001 Unknown 37 (254115B842C048) length: 14 RSSI: -38
2018.03.01 00:18:09 4: CUL_TCM97001 Device not implemented yet name Unknown msg 254115B842C048
2018.03.01 00:18:09 5: Starting notify loop for Unknown, 1 event(s), first is Code: 254115B842C0
2018.03.01 00:18:09 5: End notify loop for Unknown
2018.03.01 00:18:09 4: CUL_Parse: meinCul s254115B842C047;  432: 7856
2018.03.01 00:18:09 5: meinCul: dispatch s254115B842C047;  432: 7856
2018.03.01 00:18:09 4: CUL_TCM97001 Unknown 37 (254115B842C047) length: 14 RSSI: -38.5
2018.03.01 00:18:09 4: CUL_TCM97001 Device not implemented yet name Unknown msg 254115B842C047
2018.03.01 00:18:09 5: Starting notify loop for Unknown, 1 event(s), first is Code: 254115B842C0
2018.03.01 00:18:09 5: End notify loop for Unknown
2018.03.01 00:18:09 4: CUL_Parse: meinCul s254115B842C048;  416: 7872
2018.03.01 00:18:09 5: meinCul: dispatch s254115B842C048;  416: 7872
2018.03.01 00:18:09 4: CUL_TCM97001 Unknown 37 (254115B842C048) length: 14 RSSI: -38
2018.03.01 00:18:09 4: CUL_TCM97001 Device not implemented yet name Unknown msg 254115B842C048
2018.03.01 00:18:09 5: Starting notify loop for Unknown, 1 event(s), first is Code: 254115B842C0
2018.03.01 00:18:09 5: End notify loop for Unknown

M.E. ist der Bereich des USB create unterschiedlich. Aber warum? Und vor allem, das Problem habe ich bei jedem Neustart des PI und von FHEM; es ist also reproduzierbar. Zur Ergänzung, sofern ich mit sudo service fhem stop und start arbeite, stürzt fhem beim Telnet-Dienst ab
2018.03.01 00:47:02 5: Initializing Type Library:
2018.03.01 00:47:02 1: Including fhem.cfg
2018.03.01 00:47:02 5: Cmd: >attr global userattr cmdIcon devStateIcon devStateStyle icon sortby webCmd widgetOverride<
2018.03.01 00:47:02 5: Cmd: >attr global autoload_undefined_devices 1<
2018.03.01 00:47:02 5: Cmd: >attr global logfile ./log/fhem-%Y-%m.log<
2018.03.01 00:47:02 5: Cmd: >attr global modpath .<
2018.03.01 00:47:02 5: Cmd: >attr global motd SecurityCheck:

WEB,WEBphone,WEBtablet has no associated allowed device with basicAuth.
telnetPort has no associated allowed device with password/globalpassword.

Restart FHEM for a new check if the problem is fixed,
or set the global attribute motd to none to supress this message.
<
2018.03.01 00:47:03 5: Cmd: >attr global statefile ./log/fhem.save<
2018.03.01 00:47:03 5: Cmd: >attr global updateInBackground 1<
2018.03.01 00:47:03 5: Cmd: >attr global verbose 5<
2018.03.01 00:47:03 5: Cmd: >define telnetPort telnet 7072 global<
2018.03.01 00:47:03 5: Loading ./FHEM/98_telnet.pm
2018.03.01 00:47:03 1: telnetPort: Can't open server port at 7072: Address already in use. Exiting.


Erst ein ab- und anstecken des Cul löst das Problem, zieht aber auch ein Neustart des gesamten PI nach sich.

Zur Zeit läuft der SelbstbauCul nur an einem Testsystem und da akzeptiere ich das Verhalten zwar, aber in mein Produktivsystem schafft der CUL das so nicht ;-).

Gruß

Mundus

RaspiLED

Hi,
schau Dir mal

sudo dmesg


sudo cat /var/log/syslog

Aber das ist nicht meine Vermutung:
Du gast einen china arduino clone mit FTDI Chip, richtig?
Verbinde mal den TEST-Pin mit dem AGND-Pin des FTDI, dann bootet der nanoCUL auch inkl. Reset ;-)
Vgl. https://ketturi.kapsi.fi/2014/04/how-to-fix-moody-arduino-nano/

Gruß Arnd


Raspi2 mit FHEM, CUL, Signalduino, MySensors, HomeBridge, Presence, Bravia, ...
Raspberry Pi mit FHEM, CUL, Signalduino, MySensors, HomeBridge, Presence, WifiLight2, Bravia, ...

KölnSolar

Das usb create brauchst Du doch gar nicht. Hab schon öfter gelesen, dass das Probleme bereitete.
Grüße Markus
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

Mundus

#3
Hi, sudo dmesg gibt nach einem Neustart folgendes aus[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.9.59+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #1047 Sun Oct 29 11:47:10 GMT 2017
[    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
[    0.000000] OF: fdt:Machine model: Raspberry Pi Model B Rev 2
[    0.000000] cma: Reserved 8 MiB at 0x1b400000
[    0.000000] Memory policy: Data cache writeback
[    0.000000] On node 0 totalpages: 114688
[    0.000000] free_area_init_node: node 0, pgdat c0914e10, node_mem_map db010000
[    0.000000]   Normal zone: 1008 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 114688 pages, LIFO batch:31
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc:
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 113680
[    0.000000] Kernel command line: bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000  dwc_otg.lpm_enable=0 console=ttyAMA0,115200 console=tty1 root=PARTUUID=83103ae6-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Memory: 435948K/458752K available (5950K kernel code, 491K rwdata, 1948K rodata, 396K init, 725K bss, 14612K reserved, 8192K cma-reserved)
[    0.000000] Virtual kernel memory layout:
                   vector  : 0xffff0000 - 0xffff1000   (   4 kB)
                   fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
                   vmalloc : 0xdc800000 - 0xff800000   ( 560 MB)
                   lowmem  : 0xc0000000 - 0xdc000000   ( 448 MB)
                   modules : 0xbf000000 - 0xc0000000   (  16 MB)
                     .text : 0xc0008000 - 0xc05d7a48   (5951 kB)
                     .init : 0xc0841000 - 0xc08a4000   ( 396 kB)
                     .data : 0xc08a4000 - 0xc091ef48   ( 492 kB)
                      .bss : 0xc091ef48 - 0xc09d4648   ( 726 kB)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000031] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[    0.000075] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[    0.000176] bcm2835: system timer (irq = 27)
[    0.000642] Console: colour dummy device 80x30
[    0.001381] console [tty1] enabled
[    0.001439] Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792)
[    0.060365] pid_max: default: 32768 minimum: 301
[    0.060829] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.060899] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.062191] Disabling memory control group subsystem
[    0.062397] CPU: Testing write buffer coherency: ok
[    0.062501] ftrace: allocating 21715 entries in 64 pages
[    0.180641] Setting up static identity map for 0x8200 - 0x8238
[    0.182720] devtmpfs: initialized
[    0.192123] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
[    0.192650] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.192752] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.194062] pinctrl core: initialized pinctrl subsystem
[    0.195568] NET: Registered protocol family 16
[    0.198019] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.207586] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
[    0.207681] hw-breakpoint: maximum watchpoint size is 4 bytes.
[    0.207823] Serial: AMBA PL011 UART driver
[    0.210757] bcm2835-mbox 2000b880.mailbox: mailbox enabled
[    0.261020] bcm2835-dma 20007000.dma: DMA legacy API manager at dc80d000, dmachans=0x1
[    0.263764] SCSI subsystem initialized
[    0.264107] usbcore: registered new interface driver usbfs
[    0.264283] usbcore: registered new interface driver hub
[    0.264524] usbcore: registered new device driver usb
[    0.270724] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-10-24 17:09
[    0.272712] clocksource: Switched to clocksource timer
[    0.326939] VFS: Disk quotas dquot_6.6.0
[    0.327141] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.327515] FS-Cache: Loaded
[    0.327907] CacheFiles: Loaded
[    0.347330] NET: Registered protocol family 2
[    0.348765] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.348924] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[    0.349058] TCP: Hash tables configured (established 4096 bind 4096)
[    0.349195] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.349262] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.349624] NET: Registered protocol family 1
[    0.350431] RPC: Registered named UNIX socket transport module.
[    0.350506] RPC: Registered udp transport module.
[    0.350544] RPC: Registered tcp transport module.
[    0.350580] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.351697] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available
[    0.354349] workingset: timestamp_bits=14 max_order=17 bucket_order=3
[    0.375079] FS-Cache: Netfs 'nfs' registered for caching
[    0.376926] NFS: Registering the id_resolver key type
[    0.377040] Key type id_resolver registered
[    0.377084] Key type id_legacy registered
[    0.381561] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.382026] io scheduler noop registered
[    0.382092] io scheduler deadline registered (default)
[    0.382601] io scheduler cfq registered
[    0.388632] BCM2708FB: allocated DMA memory 5b500000
[    0.388765] BCM2708FB: allocated DMA channel 0 @ dc80d000
[    0.397077] Console: switching to colour frame buffer device 82x26
[    0.407447] bcm2835-rng 20104000.rng: hwrng registered
[    0.410264] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
[    0.416653] vc-sm: Videocore shared memory driver
[    0.444194] brd: module loaded
[    0.459549] loop: module loaded
[    0.462299] Loading iSCSI transport class v2.0-870.
[    0.465971] usbcore: registered new interface driver smsc95xx
[    0.468705] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    0.699593] Core Release: 2.80a
[    0.702266] Setting default values for core params
[    0.704974] Finished setting default values for core params
[    0.908106] Using Buffer DMA mode
[    0.910733] Periodic Transfer Interrupt Enhancement - disabled
[    0.913354] Multiprocessor Interrupt Enhancement - disabled
[    0.916002] OTG VER PARAM: 0, OTG VER FLAG: 0
[    0.918682] Dedicated Tx FIFOs mode
[    0.921898] WARN::dwc_otg_hcd_init:1032: FIQ DMA bounce buffers: virt = 0xdb514000 dma = 0x5b514000 len=9024
[    0.927538] FIQ FSM acceleration enabled for :
               Non-periodic Split Transactions
               Periodic Split Transactions
               High-Speed Isochronous Endpoints
               Interrupt/Control Split Transaction hack enabled
[    0.940662] dwc_otg: Microframe scheduler enabled
[    0.940798] WARN::hcd_init_fiq:459: FIQ on core 0 at 0xc0451694
[    0.943441] WARN::hcd_init_fiq:460: FIQ ASM at 0xc0451970 length 36
[    0.946043] WARN::hcd_init_fiq:486: MPHI regs_base at 0xdc8a5000
[    0.948821] dwc_otg 20980000.usb: DWC OTG Controller
[    0.951521] dwc_otg 20980000.usb: new USB bus registered, assigned bus number 1
[    0.954373] dwc_otg 20980000.usb: irq 56, io mem 0x00000000
[    0.957048] Init: Port Power? op_state=1
[    0.959598] Init: Power Port (0)
[    0.962392] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    0.965118] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.967750] usb usb1: Product: DWC OTG Controller
[    0.970306] usb usb1: Manufacturer: Linux 4.9.59+ dwc_otg_hcd
[    0.972934] usb usb1: SerialNumber: 20980000.usb
[    0.976700] hub 1-0:1.0: USB hub found
[    0.979284] hub 1-0:1.0: 1 port detected
[    0.982651] dwc_otg: FIQ enabled
[    0.982661] dwc_otg: NAK holdoff enabled
[    0.982667] dwc_otg: FIQ split-transaction FSM enabled
[    0.982688] Module dwc_common_port init
[    0.983276] usbcore: registered new interface driver usb-storage
[    0.986278] mousedev: PS/2 mouse device common for all mice
[    0.990480] bcm2835-wdt 20100000.watchdog: Broadcom BCM2835 watchdog timer
[    0.993738] bcm2835-cpufreq: min=700000 max=700000
[    0.996956] sdhci: Secure Digital Host Controller Interface driver
[    0.999629] sdhci: Copyright(c) Pierre Ossman
[    1.002665] sdhost-bcm2835 20202000.sdhost: could not get clk, deferring probe
[    1.005848] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.009178] ledtrig-cpu: registered to indicate activity on CPUs
[    1.012069] hidraw: raw HID events driver (C) Jiri Kosina
[    1.015321] usbcore: registered new interface driver usbhid
[    1.018119] usbhid: USB HID core driver
[    1.022046] vchiq: vchiq_init_state: slot_zero = 0xdb580000, is_master = 0
[    1.026937] [vc_sm_connected_init]: start
[    1.037295] [vc_sm_connected_init]: end - returning 0
[    1.040744] Initializing XFRM netlink socket
[    1.043635] NET: Registered protocol family 17
[    1.046489] Key type dns_resolver registered
[    1.050912] registered taskstats version 1
[    1.061024] uart-pl011 20201000.serial: cts_event_workaround enabled
[    1.064264] 20201000.serial: ttyAMA0 at MMIO 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2
[    1.888980] console [ttyAMA0] enabled
[    1.898037] sdhost: log_buf @ db513000 (5b513000)
[    1.982797] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    1.991302] of_cfs_init
[    2.016820] of_cfs_init: OK
[    2.023273] Waiting for root device PARTUUID=83103ae6-02...
[    2.033115] Indeed it is in host mode hprt0 = 00021501
[    2.101704] random: fast init done
[    2.151420] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.167371] mmc0: new high speed SDHC card at address 0007
[    2.177249] mmcblk0: mmc0:0007 SD04G 3.71 GiB
[    2.187626]  mmcblk0: p1 p2
[    2.242820] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    2.252365] Indeed it is in host mode hprt0 = 00001101
[    2.338724] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    2.352915] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    2.375297] devtmpfs: mounted
[    2.382626] Freeing unused kernel memory: 396K
[    2.390120] This architecture does not have kernel memory protection.
[    2.493668] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
[    2.503767] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.515633] hub 1-1:1.0: USB hub found
[    2.523131] hub 1-1:1.0: 5 ports detected
[    2.852852] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    2.993320] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[    3.003680] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.018342] smsc95xx v1.0.5
[    3.060070] systemd[1]: System time before build time, advancing clock.
[    3.119645] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-20980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:c5:fd:16
[    3.228907] NET: Registered protocol family 10
[    3.236726] usb 1-1.2: new full-speed USB device number 4 using dwc_otg
[    3.275044] ip_tables: (C) 2000-2006 Netfilter Core Team
[    3.333135] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[    3.362193] systemd[1]: Detected architecture arm.
[    3.396079] systemd[1]: Set hostname to <raspberrypi>.
[    3.417962] usb 1-1.2: New USB device found, idVendor=0403, idProduct=6001
[    3.428532] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.439090] usb 1-1.2: Product: FT232R USB UART
[    3.446827] usb 1-1.2: Manufacturer: FTDI
[    3.453926] usb 1-1.2: SerialNumber: A505MQF0
[    3.786442] uart-pl011 20201000.serial: no DMA platform data
[    4.524656] systemd[1]: Reached target Swap.
[    4.562803] systemd[1]: Listening on Syslog Socket.
[    4.575273] systemd[1]: Listening on udev Control Socket.
[    4.588065] systemd[1]: Listening on udev Kernel Socket.
[    4.600542] systemd[1]: Listening on fsck to fsckd communication Socket.
[    4.614832] systemd[1]: Listening on Journal Socket.
[    4.628247] systemd[1]: Created slice System Slice.
[    7.182261] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    7.706254] systemd-journald[106]: Received request to flush runtime journal from PID 1
[    9.511271] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000
[   12.579078] usbcore: registered new interface driver usbserial
[   12.579221] usbcore: registered new interface driver usbserial_generic
[   12.579352] usbserial: USB Serial support registered for generic
[   12.876379] usbcore: registered new interface driver ftdi_sio
[   12.876541] usbserial: USB Serial support registered for FTDI USB Serial Device
[   12.877053] ftdi_sio 1-1.2:1.0: FTDI USB Serial Device converter detected
[   12.877387] usb 1-1.2: Detected FT232RL
[   13.055166] usb 1-1.2: FTDI USB Serial Device converter now attached to ttyUSB0
[   16.257417] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[   17.851270] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SSFS
[   17.913304] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
[   24.487741] random: crng init done

Für mich sieht das nicht schlecht aus, wobei ich die 4 [warn] Meldungen als unkritisch interpretiere. Das syslog ist kritischer, aber nicht auskunftsfreudiger:-)

Mar  1 12:44:14 raspberrypi systemd[1]: Created slice User Slice of pi.
Mar  1 12:44:14 raspberrypi systemd[1]: Starting User Manager for UID 1000...
Mar  1 12:44:14 raspberrypi systemd[1]: Started Session c2 of user pi.
Mar  1 12:44:14 raspberrypi systemd[838]: Listening on GnuPG cryptographic agent (access for web browsers).
Mar  1 12:44:14 raspberrypi systemd[838]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Mar  1 12:44:14 raspberrypi systemd[838]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Mar  1 12:44:14 raspberrypi systemd[838]: Listening on GnuPG cryptographic agent and passphrase cache.
Mar  1 12:44:14 raspberrypi systemd[838]: Reached target Timers.
Mar  1 12:44:14 raspberrypi systemd[838]: Reached target Sockets.
Mar  1 12:44:14 raspberrypi systemd[838]: Reached target Paths.
Mar  1 12:44:14 raspberrypi systemd[838]: Reached target Basic System.
Mar  1 12:44:14 raspberrypi systemd[838]: Reached target Default.
Mar  1 12:44:14 raspberrypi systemd[838]: Startup finished in 208ms.
Mar  1 12:44:14 raspberrypi systemd[1]: Started User Manager for UID 1000.
Mar  1 12:46:30 raspberrypi systemd[1]: Unmounting RPC Pipe File System...
Mar  1 12:46:30 raspberrypi systemd[1]: Stopped target Graphical Interface.
Mar  1 12:46:30 raspberrypi systemd[1]: Stopped target Multi-User System.
Mar  1 12:46:30 raspberrypi systemd[1]: Stopping Regular background program processing daemon...
Mar  1 12:46:45 raspberrypi fake-hwclock[85]: Thu  1 Mar 11:46:35 UTC 2018
Mar  1 12:46:45 raspberrypi systemd-fsck[90]: e2fsck 1.43.4 (31-Jan-2017)
Mar  1 12:46:45 raspberrypi systemd-fsck[90]: rootfs: clean, 50568/237120 files, 370970/960000 blocks
Mar  1 12:46:45 raspberrypi systemd[1]: Started Set the console keyboard layout.
Mar  1 12:46:45 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Load/Save Random Seed...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting udev Coldplug all Devices...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Flush Journal to Persistent Storage...
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
Mar  1 12:46:45 raspberrypi systemd[1]: Started Load/Save Random Seed.
Mar  1 12:46:45 raspberrypi systemd[1]: Started Flush Journal to Persistent Storage.
Mar  1 12:46:45 raspberrypi systemd[1]: Started udev Kernel Device Manager.
Mar  1 12:46:45 raspberrypi systemd[1]: Started udev Coldplug all Devices.
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Show Plymouth Boot Screen...
Mar  1 12:46:45 raspberrypi systemd[1]: Received SIGRTMIN+20 from PID 149 (plymouthd).
Mar  1 12:46:45 raspberrypi systemd[1]: Started Show Plymouth Boot Screen.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Paths.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Encrypted Volumes.
Mar  1 12:46:45 raspberrypi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Mar  1 12:46:45 raspberrypi systemd[1]: Found device /dev/ttyAMA0.
Mar  1 12:46:45 raspberrypi systemd[1]: Found device /dev/disk/by-partuuid/83103ae6-01.
Mar  1 12:46:45 raspberrypi systemd[1]: Starting File System Check on /dev/disk/by-partuuid/83103ae6-01...
Mar  1 12:46:45 raspberrypi systemd-fsck[166]: fsck.fat 4.1 (2017-01-24)
Mar  1 12:46:45 raspberrypi systemd-fsck[166]: /dev/mmcblk0p1: 144 files, 42654/83705 clusters
Mar  1 12:46:45 raspberrypi systemd[1]: Started File System Check on /dev/disk/by-partuuid/83103ae6-01.
Mar  1 12:46:45 raspberrypi systemd[1]: Mounting /boot...
Mar  1 12:46:45 raspberrypi mtp-probe: checking bus 1, device 3: "/sys/devices/platform/soc/20980000.usb/usb1/1-1/1-1.1"
Mar  1 12:46:45 raspberrypi mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/20980000.usb/usb1/1-1/1-1.2"
Mar  1 12:46:45 raspberrypi mtp-probe: bus: 1, device: 4 was not an MTP device
Mar  1 12:46:45 raspberrypi systemd[1]: Mounted /boot.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Local File Systems.
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Raise network interfaces...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Preprocess NFS configuration...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Set console font and keymap...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Create Volatile Files and Directories...
Mar  1 12:46:45 raspberrypi systemd[1]: Started Preprocess NFS configuration.
Mar  1 12:46:45 raspberrypi systemd[1]: Started Set console font and keymap.
Mar  1 12:46:45 raspberrypi systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target NFS client services.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Remote File Systems (Pre).
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Remote File Systems.
Mar  1 12:46:45 raspberrypi mtp-probe: bus: 1, device: 3 was not an MTP device
Mar  1 12:46:45 raspberrypi systemd[1]: Started Create Volatile Files and Directories.
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Network Time Synchronization...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Mar  1 12:46:45 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Sound Card.
Mar  1 12:46:45 raspberrypi systemd[1]: Started Network Time Synchronization.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target System Initialization.
Mar  1 12:46:45 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
Mar  1 12:46:45 raspberrypi systemd[1]: Listening on triggerhappy.socket.
Mar  1 12:46:45 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Mar  1 12:46:45 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Sockets.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Basic System.
Mar  1 12:46:45 raspberrypi systemd[1]: Starting triggerhappy global hotkey daemon...
Mar  1 12:46:45 raspberrypi systemd[1]: Started D-Bus System Message Bus.
Mar  1 12:46:45 raspberrypi thd[236]: Found socket passed from systemd
Mar  1 12:46:45 raspberrypi systemd[1]: Starting LSB: FHEM server...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Login Service...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
Mar  1 12:46:45 raspberrypi systemd[1]: Started Regular background program processing daemon.
Mar  1 12:46:45 raspberrypi systemd[1]: Starting System Logging Service...
Mar  1 12:46:45 raspberrypi avahi-daemon[241]: Found user 'avahi' (UID 108) and group 'avahi' (GID 112).
Mar  1 12:46:45 raspberrypi avahi-daemon[241]: Successfully dropped root privileges.
Mar  1 12:46:45 raspberrypi avahi-daemon[241]: avahi-daemon 0.6.32 starting up.
Mar  1 12:46:45 raspberrypi cron[246]: (CRON) INFO (pidfile fd = 3)
Mar  1 12:46:45 raspberrypi systemd[1]: Starting Save/Restore Sound Card State...
Mar  1 12:46:45 raspberrypi systemd[1]: Starting LSB: Autogenerate and use a swap file...
Mar  1 12:46:45 raspberrypi avahi-daemon[241]: Successfully called chroot().
Mar  1 12:46:45 raspberrypi avahi-daemon[241]: Successfully dropped remaining capabilities.
Mar  1 12:46:45 raspberrypi avahi-daemon[241]: No service file found in /etc/avahi/services.
Mar  1 12:46:45 raspberrypi avahi-daemon[241]: Network interface enumeration completed.
Mar  1 12:46:45 raspberrypi cron[246]: (CRON) INFO (Running @reboot jobs)
Mar  1 12:46:45 raspberrypi systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Mar  1 12:46:45 raspberrypi avahi-daemon[241]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 3345070332.
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target System Time Synchronized.
Mar  1 12:46:45 raspberrypi dhcpcd[244]: dev: loaded udev
Mar  1 12:46:45 raspberrypi systemd[1]: apt-daily.timer: Adding 8h 39min 17.581433s random time.
Mar  1 12:46:45 raspberrypi systemd[1]: Started Daily apt download activities.
Mar  1 12:46:45 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 29min 38.422443s random time.
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0x0
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] Linux version 4.9.59+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #1047 Sun Oct 29 11:47:10 GMT 2017
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] OF: fdt:Machine model: Raspberry Pi Model B Rev 2
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] cma: Reserved 8 MiB at 0x1b400000
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] Memory policy: Data cache writeback
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] On node 0 totalpages: 114688
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat c0914e10, node_mem_map db010000
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]   Normal zone: 1008 pages used for memmap
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]   Normal zone: 114688 pages, LIFO batch:31
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 113680
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] Kernel command line: bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000  dwc_otg.lpm_enable=0 console=ttyAMA0,115200 console=tty1 root=PARTUUID=83103ae6-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] Memory: 435948K/458752K available (5950K kernel code, 491K rwdata, 1948K rodata, 396K init, 725K bss, 14612K reserved, 8192K cma-reserved)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] Virtual kernel memory layout:
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]     vmalloc : 0xdc800000 - 0xff800000   ( 560 MB)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xdc000000   ( 448 MB)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]       .text : 0xc0008000 - 0xc05d7a48   (5951 kB)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]       .init : 0xc0841000 - 0xc08a4000   ( 396 kB)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]       .data : 0xc08a4000 - 0xc091ef48   ( 492 kB)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000]        .bss : 0xc091ef48 - 0xc09d4648   ( 726 kB)
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Mar  1 12:46:45 raspberrypi kernel: [    0.000000] NR_IRQS:16 nr_irqs:16 16
Mar  1 12:46:45 raspberrypi kernel: [    0.000031] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
Mar  1 12:46:45 raspberrypi kernel: [    0.000075] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
Mar  1 12:46:45 raspberrypi kernel: [    0.000176] bcm2835: system timer (irq = 27)
Mar  1 12:46:45 raspberrypi kernel: [    0.000642] Console: colour dummy device 80x30
Mar  1 12:46:45 raspberrypi kernel: [    0.001381] console [tty1] enabled
Mar  1 12:46:45 raspberrypi kernel: [    0.001439] Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792)
Mar  1 12:46:45 raspberrypi kernel: [    0.060365] pid_max: default: 32768 minimum: 301
Mar  1 12:46:45 raspberrypi kernel: [    0.060829] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.060899] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.062191] Disabling memory control group subsystem
Mar  1 12:46:45 raspberrypi kernel: [    0.062397] CPU: Testing write buffer coherency: ok
Mar  1 12:46:45 raspberrypi kernel: [    0.062501] ftrace: allocating 21715 entries in 64 pages
Mar  1 12:46:45 raspberrypi kernel: [    0.180641] Setting up static identity map for 0x8200 - 0x8238
Mar  1 12:46:45 raspberrypi kernel: [    0.182720] devtmpfs: initialized
Mar  1 12:46:45 raspberrypi kernel: [    0.192123] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
Mar  1 12:46:45 raspberrypi kernel: [    0.192650] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Mar  1 12:46:45 raspberrypi kernel: [    0.192752] futex hash table entries: 256 (order: -1, 3072 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.194062] pinctrl core: initialized pinctrl subsystem
Mar  1 12:46:45 raspberrypi kernel: [    0.195568] NET: Registered protocol family 16
Mar  1 12:46:45 raspberrypi kernel: [    0.198019] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Mar  1 12:46:45 raspberrypi kernel: [    0.207586] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
Mar  1 12:46:45 raspberrypi kernel: [    0.207681] hw-breakpoint: maximum watchpoint size is 4 bytes.
Mar  1 12:46:45 raspberrypi kernel: [    0.207823] Serial: AMBA PL011 UART driver
Mar  1 12:46:45 raspberrypi kernel: [    0.210757] bcm2835-mbox 2000b880.mailbox: mailbox enabled
Mar  1 12:46:45 raspberrypi kernel: [    0.261020] bcm2835-dma 20007000.dma: DMA legacy API manager at dc80d000, dmachans=0x1
Mar  1 12:46:45 raspberrypi kernel: [    0.263764] SCSI subsystem initialized
Mar  1 12:46:45 raspberrypi kernel: [    0.264107] usbcore: registered new interface driver usbfs
Mar  1 12:46:45 raspberrypi kernel: [    0.264283] usbcore: registered new interface driver hub
Mar  1 12:46:45 raspberrypi kernel: [    0.264524] usbcore: registered new device driver usb
Mar  1 12:46:45 raspberrypi kernel: [    0.270724] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-10-24 17:09
Mar  1 12:46:45 raspberrypi kernel: [    0.272712] clocksource: Switched to clocksource timer
Mar  1 12:46:45 raspberrypi kernel: [    0.326939] VFS: Disk quotas dquot_6.6.0
Mar  1 12:46:45 raspberrypi kernel: [    0.327141] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.327515] FS-Cache: Loaded
Mar  1 12:46:45 raspberrypi kernel: [    0.327907] CacheFiles: Loaded
Mar  1 12:46:45 raspberrypi kernel: [    0.347330] NET: Registered protocol family 2
Mar  1 12:46:45 raspberrypi kernel: [    0.348765] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.348924] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.349058] TCP: Hash tables configured (established 4096 bind 4096)
Mar  1 12:46:45 raspberrypi kernel: [    0.349195] UDP hash table entries: 256 (order: 0, 4096 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.349262] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Mar  1 12:46:45 raspberrypi kernel: [    0.349624] NET: Registered protocol family 1
Mar  1 12:46:45 raspberrypi kernel: [    0.350431] RPC: Registered named UNIX socket transport module.
Mar  1 12:46:45 raspberrypi kernel: [    0.350506] RPC: Registered udp transport module.
Mar  1 12:46:45 raspberrypi kernel: [    0.350544] RPC: Registered tcp transport module.
Mar  1 12:46:45 raspberrypi kernel: [    0.350580] RPC: Registered tcp NFSv4.1 backchannel transport module.
Mar  1 12:46:45 raspberrypi kernel: [    0.351697] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available
Mar  1 12:46:45 raspberrypi kernel: [    0.354349] workingset: timestamp_bits=14 max_order=17 bucket_order=3
Mar  1 12:46:45 raspberrypi kernel: [    0.375079] FS-Cache: Netfs 'nfs' registered for caching
Mar  1 12:46:45 raspberrypi kernel: [    0.376926] NFS: Registering the id_resolver key type
Mar  1 12:46:45 raspberrypi kernel: [    0.377040] Key type id_resolver registered
Mar  1 12:46:45 raspberrypi kernel: [    0.377084] Key type id_legacy registered
Mar  1 12:46:45 raspberrypi kernel: [    0.381561] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Mar  1 12:46:45 raspberrypi kernel: [    0.382026] io scheduler noop registered
Mar  1 12:46:45 raspberrypi kernel: [    0.382092] io scheduler deadline registered (default)
Mar  1 12:46:45 raspberrypi kernel: [    0.382601] io scheduler cfq registered
Mar  1 12:46:45 raspberrypi kernel: [    0.388632] BCM2708FB: allocated DMA memory 5b500000
Mar  1 12:46:45 raspberrypi kernel: [    0.388765] BCM2708FB: allocated DMA channel 0 @ dc80d000
Mar  1 12:46:45 raspberrypi kernel: [    0.397077] Console: switching to colour frame buffer device 82x26
Mar  1 12:46:45 raspberrypi kernel: [    0.407447] bcm2835-rng 20104000.rng: hwrng registered
Mar  1 12:46:45 raspberrypi kernel: [    0.410264] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
Mar  1 12:46:45 raspberrypi kernel: [    0.416653] vc-sm: Videocore shared memory driver
Mar  1 12:46:45 raspberrypi kernel: [    0.444194] brd: module loaded
Mar  1 12:46:45 raspberrypi kernel: [    0.459549] loop: module loaded
Mar  1 12:46:45 raspberrypi kernel: [    0.462299] Loading iSCSI transport class v2.0-870.
Mar  1 12:46:45 raspberrypi kernel: [    0.465971] usbcore: registered new interface driver smsc95xx
Mar  1 12:46:45 raspberrypi kernel: [    0.468705] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Mar  1 12:46:45 raspberrypi kernel: [    0.699593] Core Release: 2.80a
Mar  1 12:46:45 raspberrypi kernel: [    0.702266] Setting default values for core params
Mar  1 12:46:45 raspberrypi kernel: [    0.704974] Finished setting default values for core params
Mar  1 12:46:45 raspberrypi kernel: [    0.908106] Using Buffer DMA mode
Mar  1 12:46:45 raspberrypi kernel: [    0.910733] Periodic Transfer Interrupt Enhancement - disabled
Mar  1 12:46:45 raspberrypi kernel: [    0.913354] Multiprocessor Interrupt Enhancement - disabled
Mar  1 12:46:45 raspberrypi kernel: [    0.916002] OTG VER PARAM: 0, OTG VER FLAG: 0
Mar  1 12:46:45 raspberrypi kernel: [    0.918682] Dedicated Tx FIFOs mode
Mar  1 12:46:45 raspberrypi kernel: [    0.921898] WARN::dwc_otg_hcd_init:1032: FIQ DMA bounce buffers: virt = 0xdb514000 dma = 0x5b514000 len=9024
Mar  1 12:46:45 raspberrypi kernel: [    0.927538] FIQ FSM acceleration enabled for :
Mar  1 12:46:45 raspberrypi kernel: [    0.927538] Non-periodic Split Transactions
Mar  1 12:46:45 raspberrypi kernel: [    0.927538] Periodic Split Transactions
Mar  1 12:46:45 raspberrypi kernel: [    0.927538] High-Speed Isochronous Endpoints
Mar  1 12:46:45 raspberrypi kernel: [    0.927538] Interrupt/Control Split Transaction hack enabled
Mar  1 12:46:45 raspberrypi kernel: [    0.940662] dwc_otg: Microframe scheduler enabled
Mar  1 12:46:45 raspberrypi kernel: [    0.940798] WARN::hcd_init_fiq:459: FIQ on core 0 at 0xc0451694
Mar  1 12:46:45 raspberrypi kernel: [    0.943441] WARN::hcd_init_fiq:460: FIQ ASM at 0xc0451970 length 36
Mar  1 12:46:45 raspberrypi kernel: [    0.946043] WARN::hcd_init_fiq:486: MPHI regs_base at 0xdc8a5000
Mar  1 12:46:45 raspberrypi kernel: [    0.948821] dwc_otg 20980000.usb: DWC OTG Controller
Mar  1 12:46:45 raspberrypi kernel: [    0.951521] dwc_otg 20980000.usb: new USB bus registered, assigned bus number 1
Mar  1 12:46:45 raspberrypi kernel: [    0.954373] dwc_otg 20980000.usb: irq 56, io mem 0x00000000
Mar  1 12:46:45 raspberrypi kernel: [    0.957048] Init: Port Power? op_state=1
Mar  1 12:46:45 raspberrypi kernel: [    0.959598] Init: Power Port (0)
Mar  1 12:46:45 raspberrypi kernel: [    0.962392] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Mar  1 12:46:45 raspberrypi kernel: [    0.965118] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Mar  1 12:46:45 raspberrypi kernel: [    0.967750] usb usb1: Product: DWC OTG Controller
Mar  1 12:46:45 raspberrypi kernel: [    0.970306] usb usb1: Manufacturer: Linux 4.9.59+ dwc_otg_hcd
Mar  1 12:46:45 raspberrypi kernel: [    0.972934] usb usb1: SerialNumber: 20980000.usb
Mar  1 12:46:45 raspberrypi kernel: [    0.976700] hub 1-0:1.0: USB hub found
Mar  1 12:46:45 raspberrypi kernel: [    0.979284] hub 1-0:1.0: 1 port detected
Mar  1 12:46:45 raspberrypi kernel: [    0.982651] dwc_otg: FIQ enabled
Mar  1 12:46:45 raspberrypi kernel: [    0.982661] dwc_otg: NAK holdoff enabled
Mar  1 12:46:45 raspberrypi kernel: [    0.982667] dwc_otg: FIQ split-transaction FSM enabled
Mar  1 12:46:45 raspberrypi kernel: [    0.982688] Module dwc_common_port init
Mar  1 12:46:45 raspberrypi kernel: [    0.983276] usbcore: registered new interface driver usb-storage
Mar  1 12:46:45 raspberrypi kernel: [    0.986278] mousedev: PS/2 mouse device common for all mice
Mar  1 12:46:45 raspberrypi kernel: [    0.990480] bcm2835-wdt 20100000.watchdog: Broadcom BCM2835 watchdog timer
Mar  1 12:46:45 raspberrypi kernel: [    0.993738] bcm2835-cpufreq: min=700000 max=700000
Mar  1 12:46:45 raspberrypi kernel: [    0.996956] sdhci: Secure Digital Host Controller Interface driver
Mar  1 12:46:45 raspberrypi kernel: [    0.999629] sdhci: Copyright(c) Pierre Ossman
Mar  1 12:46:45 raspberrypi kernel: [    1.002665] sdhost-bcm2835 20202000.sdhost: could not get clk, deferring probe
Mar  1 12:46:45 raspberrypi kernel: [    1.005848] sdhci-pltfm: SDHCI platform and OF driver helper
Mar  1 12:46:45 raspberrypi kernel: [    1.009178] ledtrig-cpu: registered to indicate activity on CPUs
Mar  1 12:46:45 raspberrypi kernel: [    1.012069] hidraw: raw HID events driver (C) Jiri Kosina
Mar  1 12:46:45 raspberrypi kernel: [    1.015321] usbcore: registered new interface driver usbhid
Mar  1 12:46:45 raspberrypi kernel: [    1.018119] usbhid: USB HID core driver
Mar  1 12:46:45 raspberrypi kernel: [    1.022046] vchiq: vchiq_init_state: slot_zero = 0xdb580000, is_master = 0
Mar  1 12:46:45 raspberrypi kernel: [    1.026937] [vc_sm_connected_init]: start
Mar  1 12:46:45 raspberrypi kernel: [    1.037295] [vc_sm_connected_init]: end - returning 0
Mar  1 12:46:45 raspberrypi kernel: [    1.040744] Initializing XFRM netlink socket
Mar  1 12:46:45 raspberrypi kernel: [    1.043635] NET: Registered protocol family 17
Mar  1 12:46:45 raspberrypi kernel: [    1.046489] Key type dns_resolver registered
Mar  1 12:46:45 raspberrypi kernel: [    1.050912] registered taskstats version 1
Mar  1 12:46:45 raspberrypi kernel: [    1.061024] uart-pl011 20201000.serial: cts_event_workaround enabled
Mar  1 12:46:45 raspberrypi kernel: [    1.064264] 20201000.serial: ttyAMA0 at MMIO 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2
Mar  1 12:46:45 raspberrypi kernel: [    1.888980] console [ttyAMA0] enabled
Mar  1 12:46:45 raspberrypi kernel: [    1.898037] sdhost: log_buf @ db513000 (5b513000)
Mar  1 12:46:45 raspberrypi kernel: [    1.982797] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Mar  1 12:46:45 raspberrypi kernel: [    1.991302] of_cfs_init
Mar  1 12:46:45 raspberrypi kernel: [    2.016820] of_cfs_init: OK
Mar  1 12:46:45 raspberrypi kernel: [    2.023273] Waiting for root device PARTUUID=83103ae6-02...
Mar  1 12:46:45 raspberrypi kernel: [    2.033115] Indeed it is in host mode hprt0 = 00021501
Mar  1 12:46:45 raspberrypi kernel: [    2.101704] random: fast init done
Mar  1 12:46:45 raspberrypi kernel: [    2.151420] mmc0: host does not support reading read-only switch, assuming write-enable
Mar  1 12:46:45 raspberrypi kernel: [    2.167371] mmc0: new high speed SDHC card at address 0007
Mar  1 12:46:45 raspberrypi kernel: [    2.177249] mmcblk0: mmc0:0007 SD04G 3.71 GiB
Mar  1 12:46:45 raspberrypi kernel: [    2.187626]  mmcblk0: p1 p2
Mar  1 12:46:45 raspberrypi kernel: [    2.242820] usb 1-1: new high-speed USB device number 2 using dwc_otg
Mar  1 12:46:45 raspberrypi kernel: [    2.252365] Indeed it is in host mode hprt0 = 00001101
Mar  1 12:46:45 raspberrypi kernel: [    2.338724] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Mar  1 12:46:45 raspberrypi kernel: [    2.352915] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Mar  1 12:46:45 raspberrypi kernel: [    2.375297] devtmpfs: mounted
Mar  1 12:46:45 raspberrypi kernel: [    2.382626] Freeing unused kernel memory: 396K
Mar  1 12:46:45 raspberrypi kernel: [    2.390120] This architecture does not have kernel memory protection.
Mar  1 12:46:45 raspberrypi kernel: [    2.493668] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Mar  1 12:46:45 raspberrypi kernel: [    2.503767] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  1 12:46:45 raspberrypi kernel: [    2.515633] hub 1-1:1.0: USB hub found
Mar  1 12:46:45 raspberrypi kernel: [    2.523131] hub 1-1:1.0: 5 ports detected
Mar  1 12:46:45 raspberrypi kernel: [    2.852852] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Mar  1 12:46:45 raspberrypi kernel: [    2.993320] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Mar  1 12:46:45 raspberrypi kernel: [    3.003680] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  1 12:46:45 raspberrypi kernel: [    3.018342] smsc95xx v1.0.5
Mar  1 12:46:45 raspberrypi kernel: [    3.119645] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-20980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:c5:fd:16
Mar  1 12:46:45 raspberrypi kernel: [    3.228907] NET: Registered protocol family 10
Mar  1 12:46:45 raspberrypi kernel: [    3.236726] usb 1-1.2: new full-speed USB device number 4 using dwc_otg
Mar  1 12:46:45 raspberrypi kernel: [    3.275044] ip_tables: (C) 2000-2006 Netfilter Core Team
Mar  1 12:46:45 raspberrypi kernel: [    3.417962] usb 1-1.2: New USB device found, idVendor=0403, idProduct=6001
Mar  1 12:46:45 raspberrypi kernel: [    3.428532] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  1 12:46:45 raspberrypi kernel: [    3.439090] usb 1-1.2: Product: FT232R USB UART
Mar  1 12:46:45 raspberrypi kernel: [    3.446827] usb 1-1.2: Manufacturer: FTDI
Mar  1 12:46:45 raspberrypi kernel: [    3.453926] usb 1-1.2: SerialNumber: A505MQF0
Mar  1 12:46:45 raspberrypi kernel: [    3.786442] uart-pl011 20201000.serial: no DMA platform data
Mar  1 12:46:45 raspberrypi kernel: [    7.182261] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Mar  1 12:46:45 raspberrypi kernel: [    9.511271] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000
Mar  1 12:46:45 raspberrypi kernel: [   12.579078] usbcore: registered new interface driver usbserial
Mar  1 12:46:45 raspberrypi kernel: [   12.579221] usbcore: registered new interface driver usbserial_generic
Mar  1 12:46:45 raspberrypi kernel: [   12.579352] usbserial: USB Serial support registered for generic
Mar  1 12:46:45 raspberrypi kernel: [   12.876379] usbcore: registered new interface driver ftdi_sio
Mar  1 12:46:45 raspberrypi kernel: [   12.876541] usbserial: USB Serial support registered for FTDI USB Serial Device
Mar  1 12:46:45 raspberrypi kernel: [   12.877053] ftdi_sio 1-1.2:1.0: FTDI USB Serial Device converter detected
Mar  1 12:46:45 raspberrypi kernel: [   12.877387] usb 1-1.2: Detected FT232RL
Mar  1 12:46:45 raspberrypi kernel: [   13.055166] usb 1-1.2: FTDI USB Serial Device converter now attached to ttyUSB0
Mar  1 12:46:45 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities.
Mar  1 12:46:45 raspberrypi liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="248" x-info="http://www.rsyslog.com"] start
Mar  1 12:46:45 raspberrypi systemd[1]: Reached target Timers.
Mar  1 12:46:45 raspberrypi systemd[1]: Started triggerhappy global hotkey daemon.
Mar  1 12:46:45 raspberrypi systemd[1]: Started System Logging Service.
Mar  1 12:46:45 raspberrypi systemd[1]: Started Save/Restore Sound Card State.
Mar  1 12:46:46 raspberrypi dhcpcd[244]: eth0: waiting for carrier
Mar  1 12:46:46 raspberrypi kernel: [   16.257417] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Mar  1 12:46:46 raspberrypi dhcpcd[244]: eth0: carrier acquired
Mar  1 12:46:46 raspberrypi dhcpcd[244]: DUID 00:01:00:01:21:b0:d9:db:b8:27:eb:c5:fd:16
Mar  1 12:46:46 raspberrypi dhcpcd[244]: eth0: IAID eb:c5:fd:16
Mar  1 12:46:46 raspberrypi dhcpcd[244]: eth0: adding address fe80::7397:9727:9c79:59d2
Mar  1 12:46:46 raspberrypi dphys-swapfile[252]: Starting dphys-swapfile swapfile setup ...
Mar  1 12:46:46 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Mar  1 12:46:46 raspberrypi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Mar  1 12:46:46 raspberrypi systemd[1]: Started Login Service.
Mar  1 12:46:47 raspberrypi raspi-config[253]: Checking if shift key is held down:Error opening '/dev/input/event*': No such file or directory
Mar  1 12:46:47 raspberrypi raspi-config[253]:  No. Switching to ondemand scaling governor.
Mar  1 12:46:47 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Mar  1 12:46:47 raspberrypi dhcpcd[244]: eth0: rebinding lease of XXX.XXX.XXX.XXX
Mar  1 12:46:47 raspberrypi dhcpcd[244]: eth0: soliciting an IPv6 router
Mar  1 12:46:47 raspberrypi dphys-swapfile[252]: want /var/swap=100MByte, checking existing: keeping it
Mar  1 12:46:47 raspberrypi dhcpcd[244]: eth0: carrier lost
Mar  1 12:46:47 raspberrypi systemd[1]: Started Raise network interfaces.
Mar  1 12:46:47 raspberrypi kernel: [   17.851270] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SSFS
Mar  1 12:46:47 raspberrypi dphys-swapfile[252]: done.
Mar  1 12:46:47 raspberrypi kernel: [   17.913304] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
Mar  1 12:46:47 raspberrypi systemd[1]: Started LSB: Autogenerate and use a swap file.
Mar  1 12:46:48 raspberrypi dhcpcd[244]: eth0: deleting address fe80::7397:9727:9c79:59d2
Mar  1 12:46:48 raspberrypi dhcpcd[244]: eth0: carrier acquired
Mar  1 12:46:48 raspberrypi dhcpcd[244]: eth0: IAID eb:c5:fd:16
Mar  1 12:46:48 raspberrypi dhcpcd[244]: eth0: adding address fe80::7397:9727:9c79:59d2
Mar  1 12:46:48 raspberrypi fhem[240]: Starting fhem...
Mar  1 12:46:48 raspberrypi dhcpcd[244]: eth0: rebinding lease of XXX.XXX.XXX.XXX
Mar  1 12:46:48 raspberrypi dhcpcd[244]: eth0: probing address XXX.XXX.XXX.XXX/XX
Mar  1 12:46:49 raspberrypi dhcpcd[244]: eth0: soliciting an IPv6 router
Mar  1 12:46:50 raspberrypi avahi-daemon[241]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::7397:9727:9c79:59d2.
Mar  1 12:46:50 raspberrypi avahi-daemon[241]: New relevant interface eth0.IPv6 for mDNS.
Mar  1 12:46:50 raspberrypi avahi-daemon[241]: Registering new address record for fe80::7397:9727:9c79:59d2 on eth0.*.
Mar  1 12:46:51 raspberrypi systemd[1]: Started LSB: FHEM server.
Mar  1 12:46:54 raspberrypi dhcpcd[244]: eth0: leased XXX.XXX.XXX.XXX for 864000 seconds
Mar  1 12:46:54 raspberrypi avahi-daemon[241]: Joining mDNS multicast group on interface eth0.IPv4 with address XXX.XXX.XXX.XXX.
Mar  1 12:46:54 raspberrypi avahi-daemon[241]: New relevant interface eth0.IPv4 for mDNS.
Mar  1 12:46:54 raspberrypi avahi-daemon[241]: Registering new address record for XXX.XXX.XXX.XXX on eth0.IPv4.
Mar  1 12:46:54 raspberrypi dhcpcd[244]: eth0: adding route to XXX.XXX
Mar  1 12:46:54 raspberrypi dhcpcd[244]: eth0: adding default route via XXX.XXX.XXX.XXX
Mar  1 12:46:54 raspberrypi kernel: [   24.487741] random: crng init done
Mar  1 12:46:54 raspberrypi dhcpcd[244]: Failed to get unit file state for ntp.service: No such file or directory
Mar  1 12:46:54 raspberrypi dhcpcd[244]: forked to background, child pid 383
Mar  1 12:46:54 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
Mar  1 12:46:54 raspberrypi systemd[1]: Reached target Network.
Mar  1 12:46:54 raspberrypi systemd[1]: Starting Permit User Sessions...
Mar  1 12:46:54 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server...
Mar  1 12:46:54 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
Mar  1 12:46:55 raspberrypi systemd[1]: Started Permit User Sessions.
Mar  1 12:46:55 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
Mar  1 12:46:55 raspberrypi systemd[1]: Starting Terminate Plymouth Boot Screen...
Mar  1 12:46:55 raspberrypi systemd[1]: Starting Hold until boot process finishes up...
Mar  1 12:46:55 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 149 (plymouthd).
Mar  1 12:46:55 raspberrypi systemd[1]: Started Terminate Plymouth Boot Screen.
Mar  1 12:46:55 raspberrypi systemd[1]: Started Hold until boot process finishes up.
Mar  1 12:46:55 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:46:55 raspberrypi systemd[1]: Started Getty on tty1.
Mar  1 12:46:55 raspberrypi systemd[1]: Reached target Login Prompts.
Mar  1 12:46:55 raspberrypi systemd[1]: Started OpenBSD Secure Shell server.
Mar  1 12:47:02 raspberrypi dhcpcd[383]: eth0: no IPv6 Routers available
Mar  1 12:47:07 raspberrypi systemd[1]: Created slice User Slice of pi.
Mar  1 12:47:07 raspberrypi systemd[1]: Started Session c1 of user pi.
Mar  1 12:47:07 raspberrypi systemd[1]: Starting User Manager for UID 1000...
Mar  1 12:47:08 raspberrypi systemd[408]: Listening on GnuPG cryptographic agent (access for web browsers).
Mar  1 12:47:08 raspberrypi systemd[408]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Mar  1 12:47:08 raspberrypi systemd[408]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Mar  1 12:47:08 raspberrypi systemd[408]: Reached target Timers.
Mar  1 12:47:08 raspberrypi systemd[408]: Reached target Paths.
Mar  1 12:47:08 raspberrypi systemd[408]: Listening on GnuPG cryptographic agent and passphrase cache.
Mar  1 12:47:08 raspberrypi systemd[408]: Reached target Sockets.
Mar  1 12:47:08 raspberrypi systemd[408]: Reached target Basic System.
Mar  1 12:47:08 raspberrypi systemd[408]: Reached target Default.
Mar  1 12:47:08 raspberrypi systemd[408]: Startup finished in 459ms.
Mar  1 12:47:08 raspberrypi systemd[1]: Started User Manager for UID 1000.
Mar  1 12:47:10 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:47:10 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:47:10 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:47:28 raspberrypi systemd-timesyncd[207]: Synchronized to time server 129.70.132.37:123 (2.debian.pool.ntp.org).
Mar  1 12:47:28 raspberrypi systemd[408]: Time has been changed
Mar  1 12:47:28 raspberrypi systemd[1]: Time has been changed
Mar  1 12:47:28 raspberrypi systemd[1]: apt-daily.timer: Adding 4h 14min 57.858267s random time.
Mar  1 12:47:28 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 34min 22.698204s random time.
Mar  1 12:47:40 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:47:40 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:47:40 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:47:56 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:47:56 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:47:56 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:48:11 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:48:11 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:48:11 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:48:19 raspberrypi systemd[1]: dev-serial1.device: Job dev-serial1.device/start timed out.
Mar  1 12:48:19 raspberrypi systemd[1]: Timed out waiting for device dev-serial1.device.
Mar  1 12:48:19 raspberrypi systemd[1]: dev-serial1.device: Job dev-serial1.device/start failed with result 'timeout'.
Mar  1 12:48:19 raspberrypi systemd[1]: Reached target Multi-User System.
Mar  1 12:48:19 raspberrypi systemd[1]: Reached target Graphical Interface.
Mar  1 12:48:19 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
Mar  1 12:48:19 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
Mar  1 12:48:19 raspberrypi systemd[1]: Startup finished in 2.895s (kernel) + 1min 31.958s (userspace) = 1min 34.853s.
Mar  1 12:48:27 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:48:27 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:48:27 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:48:37 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:48:37 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:48:37 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:48:48 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:48:48 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:48:48 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:48:58 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:48:58 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:48:58 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:49:09 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:49:09 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:49:09 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:49:19 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:49:19 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:49:19 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:49:30 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:49:30 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:49:30 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:49:40 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:49:40 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:49:40 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:49:50 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:49:50 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:49:50 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:50:01 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:50:01 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:50:01 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:50:11 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.
Mar  1 12:50:11 raspberrypi systemd[1]: Stopped Serial Getty on ttyAMA0.
Mar  1 12:50:11 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  1 12:50:22 raspberrypi systemd[1]: serial-getty@ttyAMA0.service: Service hold-off time over, scheduling restart.

KölnSolar

Packst Du bitte den 2. Extrakt auch in code tags.
ttyUSB0 erkannt. fein. ttyAMA0 ?????
Ich wette das ist usb create.....
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

Bennemannc

#5
Hallo,

/dev/ttyAMA0 würde bedeuten der CUL hängt an dem GPIO Stecker des Raspberry. Nach dem Log ist der aber auf /dev/ttyUSB0 angeschlossen. Wenn das System, wie es normalerweise ist, eine serielle Console auf ttyAMA0 macht, kommt FHEM mit diese Console nicht klar.
Ändere doch bitte mal das DEF von dem CUL auf /dev/ttyUSB0.


ich tippe auch auf das USB-Create, weil beim Start der CUL ja erkannt wird.

Gruß Christoph

Cubietruck, Fhem 5.8
CC-RT-DN|LC-SW2-FM|RC-12|RC-19|LC-SW4-BA-PCB|LCp-SW1-BA-PCB|ES-PMSw1-Pl|LC-Bl1PBU-FM|PBI-4-FM|CC-VD|CC-TC|SEC-SC(2)|RC-KEY3-B|LC-Sw1PBU-FM|PB-2-FM|WDS100-C6-O|WDC7000|LC-Bl1-FM
Module: Dewpoint,FB_Callmonitor,HCS,Panstamp,at,notify,THRESHOLD,average,DOIF

Mundus

So, irgendwie hat das Forum Probleme mein letzten Tag vernünftig zu verarbeiten. In Ergänzung zu meinem #3 Beitrag

Nach dem ab- und anstecken des CUL bereinigt sich Syslog wie folgt
Mar  1 13:09:55 raspberrypi systemd[406]: Time has been changed
Mar  1 13:09:55 raspberrypi systemd-timesyncd[208]: Synchronized to time server 136.243.177.133:123 (2.debian.pool.ntp.org).
Mar  1 13:09:55 raspberrypi systemd[1]: Time has been changed
Mar  1 13:09:55 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 2min 4.437690s random time.
Mar  1 13:09:55 raspberrypi systemd[1]: apt-daily.timer: Adding 10h 32min 32.828281s random time.
Mar  1 13:10:33 raspberrypi systemd[1]: dev-serial1.device: Job dev-serial1.device/start timed out.
Mar  1 13:10:33 raspberrypi systemd[1]: Timed out waiting for device dev-serial1.device.
Mar  1 13:10:33 raspberrypi systemd[1]: dev-serial1.device: Job dev-serial1.device/start failed with result 'timeout'.
Mar  1 13:10:33 raspberrypi systemd[1]: Reached target Multi-User System.
Mar  1 13:10:33 raspberrypi systemd[1]: Reached target Graphical Interface.
Mar  1 13:10:33 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
Mar  1 13:10:33 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
Mar  1 13:10:33 raspberrypi systemd[1]: Startup finished in 7.673s (kernel) + 1min 31.887s (userspace) = 1min 39.560s.
Mar  1 13:17:01 raspberrypi CRON[473]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)


Wenn ich eure Beiträge richtig lese, dann kann ich mir das Löten sparen und muss nur usb create deaktivieren. In dem o.g. Artikel wird von ruhiger und guter Hand beim Löten gesprochen, ich gehe davon aus, dass der Test-Pin nicht auf den äußeren PINS zu finden ist und somit eine Lötung tatsächlich am Chip passieren muss, oder? Außerdem kann ich mir dies vermutlich sparen, da ich erst die Lösung USB-Create ausprobieren sollte, oder?

Gruß und Danke für eure Hilfe

RaspiLED

Hi,
Viel Glück! Zeig mal Dein define des CULs in FHEM!
Ich behaupte immer noch das der TEST Pin auf Ground gezogen werden muss!
Gruß Arnd


Raspi2 mit FHEM, CUL, Signalduino, MySensors, HomeBridge, Presence, Bravia, ...
Raspberry Pi mit FHEM, CUL, Signalduino, MySensors, HomeBridge, Presence, WifiLight2, Bravia, ...

KölnSolar

Wer weiß das schon aus der Ferne  ::)
Das deaktivieren von usb create ist easy getestet.
Hast Du dann immer noch Probleme, liegt Arndt wahrscheinlich richtig und Du musst den Lötkolben entstauben  ;D
Just do it !
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

RaspiLED

#9
Hi,
dann nutze ich das für meine Nachhilfe ;-)
Bei usbcreate passiert was genau, dass dazu führen könnte, dass nach einem Reboot der CUL (egal wie er definiert wurde) nicht mehr gefunden wird? Oder meint Ihr nur eine Riesen Pause, die als Hänger missinterpretiert wird?
https://forum.fhem.de/index.php?topic=66273.msg575546#msg575546

bzw. tiefer:
,,# Disable this to avoid looking for new USB devices on startup
define initialUsbCheck notify global:INITIALIZED usb create"
Also ein # for das define setzen in fhem.cfg oder in FHEM WebUI das notify deaktivieren oder löschen.

Gruß Arnd


Raspi2 mit FHEM, CUL, Signalduino, MySensors, HomeBridge, Presence, Bravia, ...
Raspberry Pi mit FHEM, CUL, Signalduino, MySensors, HomeBridge, Presence, WifiLight2, Bravia, ...

KölnSolar

Hi Arnd,
wie komm ich nur auf ....dt  :-[ :'(

Linux erkennt den nanoCUL ja ordentlich als ttyUSB0. Dann startet FHEM und usb create. Und das scheint partout einen busware-CUL(ttyAMAx) erkennen zu wollen.  :-\ Und das noch in einer Endlosschleife. Warum ? Who knows.  :-[ But who cares ? usb create braucht man max. beim 1.Boot als FHEM-Neueinsteiger.
Grüße Markus
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

Mundus

Hi,

ich vermute, wie Markus, das usb-create einen Befehl triggert der FHEM zum Absturz (Endlosschleife) bringt. Habe vorhin die Passage auskommentiert und einen Reboot durchgeführt. Zu diesem Zeitpunkt schien alles tadellos zu funktionieren. Leider kann ich erst heute Abend weiter testen und werde dann berichten und die LOGS online stellen.

Gruß

Mundus

Hi,
so nun meine Rückmeldung zur auskommentierten usbcreate Zeile:

Stop und Start funktioniert i.O. Das Syslog-Protokoll ist hier
Mar  2 22:23:16 raspberrypi systemd[1]: Stopping LSB: FHEM server...
Mar  2 22:23:17 raspberrypi fhem[1338]: Stopping fhem...
Mar  2 22:23:17 raspberrypi systemd[1]: Stopped LSB: FHEM server.
Mar  2 22:24:19 raspberrypi systemd[1]: Starting LSB: FHEM server...
Mar  2 22:24:20 raspberrypi fhem[1387]: Starting fhem...
Mar  2 22:24:22 raspberrypi systemd[1]: Started LSB: FHEM server.


Reboot funktioniert i.O. syslog Protokoll Mar  2 22:28:11 raspberrypi fake-hwclock[92]: Fri  2 Mar 21:28:01 UTC 2018
Mar  2 22:28:11 raspberrypi systemd-fsck[95]: e2fsck 1.43.4 (31-Jan-2017)
Mar  2 22:28:11 raspberrypi systemd-fsck[95]: rootfs: clean, 50580/237120 files, 371256/960000 blocks
Mar  2 22:28:11 raspberrypi systemd[1]: Mounted Configuration File System.
Mar  2 22:28:11 raspberrypi systemd[1]: Started File System Check on Root Device.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Apply Kernel Variables.
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Remount Root and Kernel File Systems...
Mar  2 22:28:11 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev.
Mar  2 22:28:11 raspberrypi systemd[1]: Starting udev Kernel Device Manager...
Mar  2 22:28:11 raspberrypi systemd[1]: Started Set the console keyboard layout.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Flush Journal to Persistent Storage...
Mar  2 22:28:11 raspberrypi systemd[1]: Starting udev Coldplug all Devices...
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Load/Save Random Seed...
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
Mar  2 22:28:11 raspberrypi systemd[1]: Started udev Kernel Device Manager.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Load/Save Random Seed.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Flush Journal to Persistent Storage.
Mar  2 22:28:11 raspberrypi systemd[1]: Started udev Coldplug all Devices.
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Show Plymouth Boot Screen...
Mar  2 22:28:11 raspberrypi systemd[1]: Received SIGRTMIN+20 from PID 150 (plymouthd).
Mar  2 22:28:11 raspberrypi systemd[1]: Started Show Plymouth Boot Screen.
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target Encrypted Volumes.
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target Paths.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Mar  2 22:28:11 raspberrypi systemd[1]: Found device /dev/ttyAMA0.
Mar  2 22:28:11 raspberrypi systemd[1]: Found device /dev/disk/by-partuuid/83103ae6-01.
Mar  2 22:28:11 raspberrypi systemd[1]: Starting File System Check on /dev/disk/by-partuuid/83103ae6-01...
Mar  2 22:28:11 raspberrypi systemd-fsck[165]: fsck.fat 4.1 (2017-01-24)
Mar  2 22:28:11 raspberrypi systemd-fsck[165]: /dev/mmcblk0p1: 144 files, 42654/83705 clusters
Mar  2 22:28:11 raspberrypi systemd[1]: Started File System Check on /dev/disk/by-partuuid/83103ae6-01.
Mar  2 22:28:11 raspberrypi systemd[1]: Mounting /boot...
Mar  2 22:28:11 raspberrypi systemd[1]: Mounted /boot.
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target Local File Systems.
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Raise network interfaces...
Mar  2 22:28:11 raspberrypi mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/20980000.usb/usb1/1-1/1-1.2"
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Mar  2 22:28:11 raspberrypi mtp-probe: checking bus 1, device 3: "/sys/devices/platform/soc/20980000.usb/usb1/1-1/1-1.1"
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Preprocess NFS configuration...
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Create Volatile Files and Directories...
Mar  2 22:28:11 raspberrypi mtp-probe: bus: 1, device: 4 was not an MTP device
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Set console font and keymap...
Mar  2 22:28:11 raspberrypi systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0x0
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] Linux version 4.9.59+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #1047 Sun Oct 29 11:47:10 GMT 2017
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] OF: fdt:Machine model: Raspberry Pi Model B Rev 2
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] cma: Reserved 8 MiB at 0x1b400000
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] Memory policy: Data cache writeback
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] On node 0 totalpages: 114688
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat c0914e10, node_mem_map db010000
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]   Normal zone: 1008 pages used for memmap
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]   Normal zone: 114688 pages, LIFO batch:31
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 113680
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] Kernel command line: bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000  dwc_otg.lpm_enable=0 console=ttyAMA0,115200 console=tty1 root=PARTUUID=83103ae6-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] Memory: 435948K/458752K available (5950K kernel code, 491K rwdata, 1948K rodata, 396K init, 725K bss, 14612K reserved, 8192K cma-reserved)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] Virtual kernel memory layout:
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]     vmalloc : 0xdc800000 - 0xff800000   ( 560 MB)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xdc000000   ( 448 MB)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]       .text : 0xc0008000 - 0xc05d7a48   (5951 kB)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]       .init : 0xc0841000 - 0xc08a4000   ( 396 kB)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]       .data : 0xc08a4000 - 0xc091ef48   ( 492 kB)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000]        .bss : 0xc091ef48 - 0xc09d4648   ( 726 kB)
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Mar  2 22:28:11 raspberrypi kernel: [    0.000000] NR_IRQS:16 nr_irqs:16 16
Mar  2 22:28:11 raspberrypi kernel: [    0.000032] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
Mar  2 22:28:11 raspberrypi kernel: [    0.000077] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
Mar  2 22:28:11 raspberrypi kernel: [    0.000176] bcm2835: system timer (irq = 27)
Mar  2 22:28:11 raspberrypi kernel: [    0.000645] Console: colour dummy device 80x30
Mar  2 22:28:11 raspberrypi kernel: [    0.001385] console [tty1] enabled
Mar  2 22:28:11 raspberrypi kernel: [    0.001441] Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792)
Mar  2 22:28:11 raspberrypi kernel: [    0.060365] pid_max: default: 32768 minimum: 301
Mar  2 22:28:11 raspberrypi kernel: [    0.060827] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.060897] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.062190] Disabling memory control group subsystem
Mar  2 22:28:11 raspberrypi kernel: [    0.062396] CPU: Testing write buffer coherency: ok
Mar  2 22:28:11 raspberrypi kernel: [    0.062499] ftrace: allocating 21715 entries in 64 pages
Mar  2 22:28:11 raspberrypi kernel: [    0.180660] Setting up static identity map for 0x8200 - 0x8238
Mar  2 22:28:11 raspberrypi kernel: [    0.182726] devtmpfs: initialized
Mar  2 22:28:11 raspberrypi kernel: [    0.192127] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
Mar  2 22:28:11 raspberrypi kernel: [    0.192653] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Mar  2 22:28:11 raspberrypi kernel: [    0.192754] futex hash table entries: 256 (order: -1, 3072 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.194072] pinctrl core: initialized pinctrl subsystem
Mar  2 22:28:11 raspberrypi kernel: [    0.195576] NET: Registered protocol family 16
Mar  2 22:28:11 raspberrypi kernel: [    0.198028] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Mar  2 22:28:11 raspberrypi kernel: [    0.207592] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
Mar  2 22:28:11 raspberrypi kernel: [    0.207686] hw-breakpoint: maximum watchpoint size is 4 bytes.
Mar  2 22:28:11 raspberrypi kernel: [    0.207826] Serial: AMBA PL011 UART driver
Mar  2 22:28:11 raspberrypi kernel: [    0.210757] bcm2835-mbox 2000b880.mailbox: mailbox enabled
Mar  2 22:28:11 raspberrypi kernel: [    0.261012] bcm2835-dma 20007000.dma: DMA legacy API manager at dc80d000, dmachans=0x1
Mar  2 22:28:11 raspberrypi kernel: [    0.263748] SCSI subsystem initialized
Mar  2 22:28:11 raspberrypi kernel: [    0.264096] usbcore: registered new interface driver usbfs
Mar  2 22:28:11 raspberrypi kernel: [    0.264272] usbcore: registered new interface driver hub
Mar  2 22:28:11 raspberrypi kernel: [    0.264515] usbcore: registered new device driver usb
Mar  2 22:28:11 raspberrypi kernel: [    0.270734] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-10-24 17:09
Mar  2 22:28:11 raspberrypi kernel: [    0.272720] clocksource: Switched to clocksource timer
Mar  2 22:28:11 raspberrypi kernel: [    0.326906] VFS: Disk quotas dquot_6.6.0
Mar  2 22:28:11 raspberrypi kernel: [    0.327107] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.327478] FS-Cache: Loaded
Mar  2 22:28:11 raspberrypi kernel: [    0.327868] CacheFiles: Loaded
Mar  2 22:28:11 raspberrypi kernel: [    0.347292] NET: Registered protocol family 2
Mar  2 22:28:11 raspberrypi kernel: [    0.348714] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.348873] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.349004] TCP: Hash tables configured (established 4096 bind 4096)
Mar  2 22:28:11 raspberrypi kernel: [    0.349139] UDP hash table entries: 256 (order: 0, 4096 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.349206] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Mar  2 22:28:11 raspberrypi kernel: [    0.349567] NET: Registered protocol family 1
Mar  2 22:28:11 raspberrypi kernel: [    0.350373] RPC: Registered named UNIX socket transport module.
Mar  2 22:28:11 raspberrypi kernel: [    0.350451] RPC: Registered udp transport module.
Mar  2 22:28:11 raspberrypi kernel: [    0.350491] RPC: Registered tcp transport module.
Mar  2 22:28:11 raspberrypi kernel: [    0.350527] RPC: Registered tcp NFSv4.1 backchannel transport module.
Mar  2 22:28:11 raspberrypi kernel: [    0.351655] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available
Mar  2 22:28:11 raspberrypi kernel: [    0.354303] workingset: timestamp_bits=14 max_order=17 bucket_order=3
Mar  2 22:28:11 raspberrypi kernel: [    0.375066] FS-Cache: Netfs 'nfs' registered for caching
Mar  2 22:28:11 raspberrypi kernel: [    0.376910] NFS: Registering the id_resolver key type
Mar  2 22:28:11 raspberrypi kernel: [    0.377024] Key type id_resolver registered
Mar  2 22:28:11 raspberrypi kernel: [    0.377068] Key type id_legacy registered
Mar  2 22:28:11 raspberrypi kernel: [    0.381543] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Mar  2 22:28:11 raspberrypi kernel: [    0.382012] io scheduler noop registered
Mar  2 22:28:11 raspberrypi kernel: [    0.382078] io scheduler deadline registered (default)
Mar  2 22:28:11 raspberrypi kernel: [    0.382590] io scheduler cfq registered
Mar  2 22:28:11 raspberrypi kernel: [    0.388636] BCM2708FB: allocated DMA memory 5b500000
Mar  2 22:28:11 raspberrypi kernel: [    0.388769] BCM2708FB: allocated DMA channel 0 @ dc80d000
Mar  2 22:28:11 raspberrypi kernel: [    0.397028] Console: switching to colour frame buffer device 82x26
Mar  2 22:28:11 raspberrypi kernel: [    0.407205] bcm2835-rng 20104000.rng: hwrng registered
Mar  2 22:28:11 raspberrypi kernel: [    0.410050] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
Mar  2 22:28:11 raspberrypi kernel: [    0.416488] vc-sm: Videocore shared memory driver
Mar  2 22:28:11 raspberrypi kernel: [    0.444010] brd: module loaded
Mar  2 22:28:11 raspberrypi kernel: [    0.459354] loop: module loaded
Mar  2 22:28:11 raspberrypi kernel: [    0.462161] Loading iSCSI transport class v2.0-870.
Mar  2 22:28:11 raspberrypi kernel: [    0.465833] usbcore: registered new interface driver smsc95xx
Mar  2 22:28:11 raspberrypi kernel: [    0.468577] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Mar  2 22:28:11 raspberrypi kernel: [    0.699447] Core Release: 2.80a
Mar  2 22:28:11 raspberrypi kernel: [    0.702123] Setting default values for core params
Mar  2 22:28:11 raspberrypi kernel: [    0.704817] Finished setting default values for core params
Mar  2 22:28:11 raspberrypi kernel: [    0.907895] Using Buffer DMA mode
Mar  2 22:28:11 raspberrypi kernel: [    0.910559] Periodic Transfer Interrupt Enhancement - disabled
Mar  2 22:28:11 raspberrypi kernel: [    0.913221] Multiprocessor Interrupt Enhancement - disabled
Mar  2 22:28:11 raspberrypi kernel: [    0.915866] OTG VER PARAM: 0, OTG VER FLAG: 0
Mar  2 22:28:11 raspberrypi kernel: [    0.918544] Dedicated Tx FIFOs mode
Mar  2 22:28:11 raspberrypi kernel: [    0.921737] WARN::dwc_otg_hcd_init:1032: FIQ DMA bounce buffers: virt = 0xdb514000 dma = 0x5b514000 len=9024
Mar  2 22:28:11 raspberrypi kernel: [    0.927370] FIQ FSM acceleration enabled for :
Mar  2 22:28:11 raspberrypi kernel: [    0.927370] Non-periodic Split Transactions
Mar  2 22:28:11 raspberrypi kernel: [    0.927370] Periodic Split Transactions
Mar  2 22:28:11 raspberrypi kernel: [    0.927370] High-Speed Isochronous Endpoints
Mar  2 22:28:11 raspberrypi kernel: [    0.927370] Interrupt/Control Split Transaction hack enabled
Mar  2 22:28:11 raspberrypi kernel: [    0.940493] dwc_otg: Microframe scheduler enabled
Mar  2 22:28:11 raspberrypi kernel: [    0.940625] WARN::hcd_init_fiq:459: FIQ on core 0 at 0xc0451694
Mar  2 22:28:11 raspberrypi kernel: [    0.943274] WARN::hcd_init_fiq:460: FIQ ASM at 0xc0451970 length 36
Mar  2 22:28:11 raspberrypi kernel: [    0.945930] WARN::hcd_init_fiq:486: MPHI regs_base at 0xdc8a5000
Mar  2 22:28:11 raspberrypi kernel: [    0.948705] dwc_otg 20980000.usb: DWC OTG Controller
Mar  2 22:28:11 raspberrypi kernel: [    0.951409] dwc_otg 20980000.usb: new USB bus registered, assigned bus number 1
Mar  2 22:28:11 raspberrypi kernel: [    0.954270] dwc_otg 20980000.usb: irq 56, io mem 0x00000000
Mar  2 22:28:11 raspberrypi kernel: [    0.956905] Init: Port Power? op_state=1
Mar  2 22:28:11 raspberrypi kernel: [    0.959457] Init: Power Port (0)
Mar  2 22:28:11 raspberrypi kernel: [    0.962317] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Mar  2 22:28:11 raspberrypi kernel: [    0.965061] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Mar  2 22:28:11 raspberrypi kernel: [    0.967707] usb usb1: Product: DWC OTG Controller
Mar  2 22:28:11 raspberrypi kernel: [    0.970269] usb usb1: Manufacturer: Linux 4.9.59+ dwc_otg_hcd
Mar  2 22:28:11 raspberrypi kernel: [    0.972860] usb usb1: SerialNumber: 20980000.usb
Mar  2 22:28:11 raspberrypi kernel: [    0.976624] hub 1-0:1.0: USB hub found
Mar  2 22:28:11 raspberrypi kernel: [    0.979262] hub 1-0:1.0: 1 port detected
Mar  2 22:28:11 raspberrypi kernel: [    0.982636] dwc_otg: FIQ enabled
Mar  2 22:28:11 raspberrypi kernel: [    0.982647] dwc_otg: NAK holdoff enabled
Mar  2 22:28:11 raspberrypi kernel: [    0.982652] dwc_otg: FIQ split-transaction FSM enabled
Mar  2 22:28:11 raspberrypi kernel: [    0.982672] Module dwc_common_port init
Mar  2 22:28:11 raspberrypi kernel: [    0.983261] usbcore: registered new interface driver usb-storage
Mar  2 22:28:11 raspberrypi kernel: [    0.986263] mousedev: PS/2 mouse device common for all mice
Mar  2 22:28:11 raspberrypi kernel: [    0.990372] bcm2835-wdt 20100000.watchdog: Broadcom BCM2835 watchdog timer
Mar  2 22:28:11 raspberrypi kernel: [    0.993650] bcm2835-cpufreq: min=700000 max=700000
Mar  2 22:28:11 raspberrypi kernel: [    0.996926] sdhci: Secure Digital Host Controller Interface driver
Mar  2 22:28:11 raspberrypi kernel: [    0.999610] sdhci: Copyright(c) Pierre Ossman
Mar  2 22:28:11 raspberrypi kernel: [    1.002647] sdhost-bcm2835 20202000.sdhost: could not get clk, deferring probe
Mar  2 22:28:11 raspberrypi kernel: [    1.005805] sdhci-pltfm: SDHCI platform and OF driver helper
Mar  2 22:28:11 raspberrypi kernel: [    1.009096] ledtrig-cpu: registered to indicate activity on CPUs
Mar  2 22:28:11 raspberrypi kernel: [    1.012042] hidraw: raw HID events driver (C) Jiri Kosina
Mar  2 22:28:11 raspberrypi kernel: [    1.015299] usbcore: registered new interface driver usbhid
Mar  2 22:28:11 raspberrypi kernel: [    1.018090] usbhid: USB HID core driver
Mar  2 22:28:11 raspberrypi kernel: [    1.021997] vchiq: vchiq_init_state: slot_zero = 0xdb580000, is_master = 0
Mar  2 22:28:11 raspberrypi kernel: [    1.026861] [vc_sm_connected_init]: start
Mar  2 22:28:11 raspberrypi kernel: [    1.037314] [vc_sm_connected_init]: end - returning 0
Mar  2 22:28:11 raspberrypi kernel: [    1.040696] Initializing XFRM netlink socket
Mar  2 22:28:11 raspberrypi kernel: [    1.043591] NET: Registered protocol family 17
Mar  2 22:28:11 raspberrypi kernel: [    1.046484] Key type dns_resolver registered
Mar  2 22:28:11 raspberrypi kernel: [    1.050908] registered taskstats version 1
Mar  2 22:28:11 raspberrypi kernel: [    1.060983] uart-pl011 20201000.serial: cts_event_workaround enabled
Mar  2 22:28:11 raspberrypi kernel: [    1.064256] 20201000.serial: ttyAMA0 at MMIO 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2
Mar  2 22:28:11 raspberrypi kernel: [    1.888974] console [ttyAMA0] enabled
Mar  2 22:28:11 raspberrypi kernel: [    1.897997] sdhost: log_buf @ db513000 (5b513000)
Mar  2 22:28:11 raspberrypi kernel: [    1.982803] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Mar  2 22:28:11 raspberrypi kernel: [    1.991311] of_cfs_init
Mar  2 22:28:11 raspberrypi kernel: [    2.016835] of_cfs_init: OK
Mar  2 22:28:11 raspberrypi kernel: [    2.023268] Waiting for root device PARTUUID=83103ae6-02...
Mar  2 22:28:11 raspberrypi kernel: [    2.033113] Indeed it is in host mode hprt0 = 00021501
Mar  2 22:28:11 raspberrypi kernel: [    2.101578] random: fast init done
Mar  2 22:28:11 raspberrypi kernel: [    2.151367] mmc0: host does not support reading read-only switch, assuming write-enable
Mar  2 22:28:11 raspberrypi kernel: [    2.167279] mmc0: new high speed SDHC card at address 0007
Mar  2 22:28:11 raspberrypi kernel: [    2.177163] mmcblk0: mmc0:0007 SD04G 3.71 GiB
Mar  2 22:28:11 raspberrypi kernel: [    2.187601]  mmcblk0: p1 p2
Mar  2 22:28:11 raspberrypi kernel: [    2.242821] usb 1-1: new high-speed USB device number 2 using dwc_otg
Mar  2 22:28:11 raspberrypi kernel: [    2.252393] Indeed it is in host mode hprt0 = 00001101
Mar  2 22:28:11 raspberrypi kernel: [    2.338735] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Mar  2 22:28:11 raspberrypi kernel: [    2.352914] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Mar  2 22:28:11 raspberrypi kernel: [    2.375417] devtmpfs: mounted
Mar  2 22:28:11 raspberrypi kernel: [    2.382885] Freeing unused kernel memory: 396K
Mar  2 22:28:11 raspberrypi kernel: [    2.390288] This architecture does not have kernel memory protection.
Mar  2 22:28:11 raspberrypi kernel: [    2.493595] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Mar  2 22:28:11 raspberrypi kernel: [    2.503754] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  2 22:28:11 raspberrypi kernel: [    2.515598] hub 1-1:1.0: USB hub found
Mar  2 22:28:11 raspberrypi kernel: [    2.523088] hub 1-1:1.0: 5 ports detected
Mar  2 22:28:11 raspberrypi kernel: [    2.852857] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Mar  2 22:28:11 raspberrypi kernel: [    2.993313] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Mar  2 22:28:11 raspberrypi kernel: [    3.003848] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  2 22:28:11 raspberrypi kernel: [    3.018446] smsc95xx v1.0.5
Mar  2 22:28:11 raspberrypi kernel: [    3.119667] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-20980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:c5:fd:16
Mar  2 22:28:11 raspberrypi kernel: [    3.228846] NET: Registered protocol family 10
Mar  2 22:28:11 raspberrypi kernel: [    3.236716] usb 1-1.2: new full-speed USB device number 4 using dwc_otg
Mar  2 22:28:11 raspberrypi kernel: [    3.275009] ip_tables: (C) 2000-2006 Netfilter Core Team
Mar  2 22:28:11 raspberrypi kernel: [    3.416591] usb 1-1.2: New USB device found, idVendor=0403, idProduct=6001
Mar  2 22:28:11 raspberrypi kernel: [    3.427202] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  2 22:28:11 raspberrypi kernel: [    3.437820] usb 1-1.2: Product: FT232R USB UART
Mar  2 22:28:11 raspberrypi kernel: [    3.445540] usb 1-1.2: Manufacturer: FTDI
Mar  2 22:28:11 raspberrypi kernel: [    3.452629] usb 1-1.2: SerialNumber: A505MQF0
Mar  2 22:28:11 raspberrypi kernel: [    3.761078] uart-pl011 20201000.serial: no DMA platform data
Mar  2 22:28:11 raspberrypi kernel: [    7.408556] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Mar  2 22:28:11 raspberrypi kernel: [    9.729846] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000
Mar  2 22:28:11 raspberrypi kernel: [   12.806877] usbcore: registered new interface driver usbserial
Mar  2 22:28:11 raspberrypi kernel: [   12.807021] usbcore: registered new interface driver usbserial_generic
Mar  2 22:28:11 raspberrypi kernel: [   12.807163] usbserial: USB Serial support registered for generic
Mar  2 22:28:11 raspberrypi kernel: [   13.097020] usbcore: registered new interface driver ftdi_sio
Mar  2 22:28:11 raspberrypi kernel: [   13.097176] usbserial: USB Serial support registered for FTDI USB Serial Device
Mar  2 22:28:11 raspberrypi kernel: [   13.097684] ftdi_sio 1-1.2:1.0: FTDI USB Serial Device converter detected
Mar  2 22:28:11 raspberrypi kernel: [   13.098001] usb 1-1.2: Detected FT232RL
Mar  2 22:28:11 raspberrypi kernel: [   13.251157] usb 1-1.2: FTDI USB Serial Device converter now attached to ttyUSB0
Mar  2 22:28:11 raspberrypi kernel: [   15.473131] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Mar  2 22:28:11 raspberrypi systemd[1]: Started Preprocess NFS configuration.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Set console font and keymap.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Create Volatile Files and Directories.
Mar  2 22:28:11 raspberrypi mtp-probe: bus: 1, device: 3 was not an MTP device
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Network Time Synchronization...
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target NFS client services.
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target Remote File Systems (Pre).
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target Remote File Systems.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Network Time Synchronization.
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target System Initialization.
Mar  2 22:28:11 raspberrypi systemd[1]: Listening on triggerhappy.socket.
Mar  2 22:28:11 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket.
Mar  2 22:28:11 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target Sockets.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target Basic System.
Mar  2 22:28:11 raspberrypi systemd[1]: Starting triggerhappy global hotkey daemon...
Mar  2 22:28:11 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
Mar  2 22:28:11 raspberrypi systemd[1]: Starting LSB: FHEM server...
Mar  2 22:28:11 raspberrypi thd[233]: Found socket passed from systemd
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Login Service...
Mar  2 22:28:11 raspberrypi systemd[1]: Starting LSB: Autogenerate and use a swap file...
Mar  2 22:28:11 raspberrypi dhcpcd[234]: dev: loaded udev
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Save/Restore Sound Card State...
Mar  2 22:28:11 raspberrypi systemd[1]: Started Regular background program processing daemon.
Mar  2 22:28:11 raspberrypi systemd[1]: Started D-Bus System Message Bus.
Mar  2 22:28:11 raspberrypi cron[243]: (CRON) INFO (pidfile fd = 3)
Mar  2 22:28:11 raspberrypi cron[243]: (CRON) INFO (Running @reboot jobs)
Mar  2 22:28:11 raspberrypi systemd[1]: Starting System Logging Service...
Mar  2 22:28:11 raspberrypi systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Mar  2 22:28:11 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target System Time Synchronized.
Mar  2 22:28:11 raspberrypi systemd[1]: apt-daily.timer: Adding 10h 1min 23.782173s random time.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Daily apt download activities.
Mar  2 22:28:11 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 25min 49.644160s random time.
Mar  2 22:28:11 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities.
Mar  2 22:28:11 raspberrypi systemd[1]: Reached target Timers.
Mar  2 22:28:11 raspberrypi dhcpcd[234]: eth0: waiting for carrier
Mar  2 22:28:11 raspberrypi dhcpcd[234]: eth0: waiting for carrier
Mar  2 22:28:11 raspberrypi systemd[1]: Started triggerhappy global hotkey daemon.
Mar  2 22:28:11 raspberrypi dhcpcd[234]: eth0: carrier acquired
Mar  2 22:28:11 raspberrypi liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="258" x-info="http://www.rsyslog.com"] start
Mar  2 22:28:11 raspberrypi systemd[1]: Started System Logging Service.
Mar  2 22:28:11 raspberrypi dhcpcd[234]: DUID 00:01:00:01:21:b0:d9:db:b8:27:eb:c5:fd:16
Mar  2 22:28:11 raspberrypi systemd[1]: Started Save/Restore Sound Card State.
Mar  2 22:28:11 raspberrypi dhcpcd[234]: eth0: IAID eb:c5:fd:16
Mar  2 22:28:11 raspberrypi dhcpcd[234]: eth0: adding address fe80::7397:9727:9c79:59d2
Mar  2 22:28:11 raspberrypi dhcpcd[234]: eth0: carrier lost
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Found user 'avahi' (UID 108) and group 'avahi' (GID 112).
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Successfully dropped root privileges.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: avahi-daemon 0.6.32 starting up.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Successfully called chroot().
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Successfully dropped remaining capabilities.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: No service file found in /etc/avahi/services.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::7397:9727:9c79:59d2.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: New relevant interface eth0.IPv6 for mDNS.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Network interface enumeration completed.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Registering new address record for fe80::7397:9727:9c79:59d2 on eth0.*.
Mar  2 22:28:11 raspberrypi dhcpcd[234]: eth0: deleting address fe80::7397:9727:9c79:59d2
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Withdrawing address record for fe80::7397:9727:9c79:59d2 on eth0.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 3999007797.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::7397:9727:9c79:59d2.
Mar  2 22:28:11 raspberrypi avahi-daemon[264]: Interface eth0.IPv6 no longer relevant for mDNS.
Mar  2 22:28:12 raspberrypi dphys-swapfile[240]: Starting dphys-swapfile swapfile setup ...
Mar  2 22:28:12 raspberrypi kernel: [   16.991050] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
Mar  2 22:28:12 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Mar  2 22:28:12 raspberrypi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Mar  2 22:28:12 raspberrypi dhcpcd[234]: eth0: carrier acquired
Mar  2 22:28:12 raspberrypi systemd[1]: Reached target Sound Card.
Mar  2 22:28:12 raspberrypi raspi-config[261]: Checking if shift key is held down:Error opening '/dev/input/event*': No such file or directory
Mar  2 22:28:12 raspberrypi systemd[1]: Started Login Service.
Mar  2 22:28:13 raspberrypi raspi-config[261]:  No. Switching to ondemand scaling governor.
Mar  2 22:28:13 raspberrypi dhcpcd[234]: eth0: IAID eb:c5:fd:16
Mar  2 22:28:13 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Mar  2 22:28:13 raspberrypi dhcpcd[234]: eth0: adding address fe80::7397:9727:9c79:59d2
Mar  2 22:28:13 raspberrypi dphys-swapfile[240]: want /var/swap=100MByte, checking existing: keeping it
Mar  2 22:28:13 raspberrypi dhcpcd[234]: eth0: soliciting an IPv6 router
Mar  2 22:28:13 raspberrypi systemd[1]: Started Raise network interfaces.
Mar  2 22:28:13 raspberrypi dhcpcd[234]: eth0: rebinding lease of X
Mar  2 22:28:13 raspberrypi dhcpcd[234]: eth0: probing address X
Mar  2 22:28:13 raspberrypi kernel: [   18.191761] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SSFS
Mar  2 22:28:13 raspberrypi dphys-swapfile[240]: done.
Mar  2 22:28:13 raspberrypi systemd[1]: Started LSB: Autogenerate and use a swap file.
Mar  2 22:28:14 raspberrypi fhem[235]: Starting fhem...
Mar  2 22:28:14 raspberrypi avahi-daemon[264]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::7397:9727:9c79:59d2.
Mar  2 22:28:14 raspberrypi avahi-daemon[264]: New relevant interface eth0.IPv6 for mDNS.
Mar  2 22:28:14 raspberrypi avahi-daemon[264]: Registering new address record for fe80::7397:9727:9c79:59d2 on eth0.*.
Mar  2 22:28:16 raspberrypi systemd[1]: Started LSB: FHEM server.
Mar  2 22:28:19 raspberrypi dhcpcd[234]: eth0: leased X for 864000 seconds
Mar  2 22:28:19 raspberrypi avahi-daemon[264]: Joining mDNS multicast group on interface eth0.IPv4 with address X.
Mar  2 22:28:19 raspberrypi avahi-daemon[264]: New relevant interface eth0.IPv4 for mDNS.
Mar  2 22:28:19 raspberrypi avahi-daemon[264]: Registering new address record for X on eth0.IPv4.
Mar  2 22:28:19 raspberrypi dhcpcd[234]: eth0: adding route to X
Mar  2 22:28:19 raspberrypi dhcpcd[234]: eth0: adding default route via X
Mar  2 22:28:20 raspberrypi dhcpcd[234]: Failed to get unit file state for ntp.service: No such file or directory
Mar  2 22:28:20 raspberrypi dhcpcd[234]: forked to background, child pid 379
Mar  2 22:28:20 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
Mar  2 22:28:20 raspberrypi systemd[1]: Reached target Network.
Mar  2 22:28:20 raspberrypi systemd[1]: Starting Permit User Sessions...
Mar  2 22:28:20 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
Mar  2 22:28:20 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server...
Mar  2 22:28:20 raspberrypi kernel: [   24.653615] random: crng init done
Mar  2 22:28:20 raspberrypi systemd[1]: Started Permit User Sessions.
Mar  2 22:28:20 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
Mar  2 22:28:20 raspberrypi systemd[1]: Starting Terminate Plymouth Boot Screen...
Mar  2 22:28:20 raspberrypi systemd[1]: Starting Hold until boot process finishes up...
Mar  2 22:28:20 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 150 (plymouthd).
Mar  2 22:28:20 raspberrypi systemd[1]: Started Terminate Plymouth Boot Screen.
Mar  2 22:28:20 raspberrypi systemd[1]: Started Hold until boot process finishes up.
Mar  2 22:28:21 raspberrypi systemd[1]: Started Getty on tty1.
Mar  2 22:28:21 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
Mar  2 22:28:21 raspberrypi systemd[1]: Reached target Login Prompts.
Mar  2 22:28:21 raspberrypi systemd[1]: Started OpenBSD Secure Shell server.
Mar  2 22:28:26 raspberrypi dhcpcd[379]: eth0: no IPv6 Routers available
Mar  2 22:28:55 raspberrypi systemd[1]: Time has been changed
Mar  2 22:28:55 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 55min 57.195679s random time.
Mar  2 22:28:55 raspberrypi systemd[1]: apt-daily.timer: Adding 11h 32min 6.682660s random time.
Mar  2 22:28:55 raspberrypi systemd-timesyncd[205]: Synchronized to time server 213.251.52.43:123 (2.debian.pool.ntp.org).
Mar  2 22:29:45 raspberrypi systemd[1]: dev-serial1.device: Job dev-serial1.device/start timed out.
Mar  2 22:29:45 raspberrypi systemd[1]: Timed out waiting for device dev-serial1.device.
Mar  2 22:29:45 raspberrypi systemd[1]: dev-serial1.device: Job dev-serial1.device/start failed with result 'timeout'.
Mar  2 22:29:45 raspberrypi systemd[1]: Reached target Multi-User System.
Mar  2 22:29:45 raspberrypi systemd[1]: Reached target Graphical Interface.
Mar  2 22:29:45 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
Mar  2 22:29:45 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
Mar  2 22:29:45 raspberrypi systemd[1]: Startup finished in 2.897s (kernel) + 1min 32.001s (userspace) = 1min 34.899s.
Mar  2 22:30:58 raspberrypi systemd[1]: Created slice User Slice of pi.
Mar  2 22:30:58 raspberrypi systemd[1]: Starting User Manager for UID 1000...
Mar  2 22:30:58 raspberrypi systemd[1]: Started Session c1 of user pi.
Mar  2 22:30:59 raspberrypi systemd[406]: Reached target Timers.
Mar  2 22:30:59 raspberrypi systemd[406]: Listening on GnuPG cryptographic agent and passphrase cache.
Mar  2 22:30:59 raspberrypi systemd[406]: Listening on GnuPG cryptographic agent (access for web browsers).
Mar  2 22:30:59 raspberrypi systemd[406]: Reached target Paths.
Mar  2 22:30:59 raspberrypi systemd[406]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Mar  2 22:30:59 raspberrypi systemd[406]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Mar  2 22:30:59 raspberrypi systemd[406]: Reached target Sockets.
Mar  2 22:30:59 raspberrypi systemd[406]: Reached target Basic System.
Mar  2 22:30:59 raspberrypi systemd[406]: Reached target Default.
Mar  2 22:30:59 raspberrypi systemd[406]: Startup finished in 231ms.


Das sieht schon sehr gut aus, daher spare ich mir, sofern jetzt weiterhin alles tadellos laufen sollte, das Löten. Eine Abschlussfrage habt ihr die Zeile
# Disable this to avoid looking for new USB devices on startup
define initialUsbCheck notify global:INITIALIZED usb create
in der fhem.cfg auskommentiert oder funktioniert auch das Disable über WebUI?

Gruß und Danke

Mundus

KölnSolar

Gute Frage,
vor 6 Jahren oder so hab ich es auskommentiert. Aber ist ja eigentlich nur ein notify, das Du im Unsorted Room finden müsstest und dort auch disablen kannst.
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt