Raspberry Pi einfach stehengeblieben

PeMue · 13 Mai 2018, 19:10:16

Hallo zusammen,

ich habe folgendes Problem: mein Raspberry Pi im Keller ist seit ein paar Tagen tot. Ich komme nicht mehr per WLAN drauf und auch das angeschlossene Display ändert die Uhrzeit nicht mehr. Ich hatte das Problem vor ca. 4 Monaten schon einmal, da habe ich einen harten Reset (Spannung aus, Spannung an) gemacht, dann lief wieder alles.
Nachdem ich das Problem nicht weiter eingrenzen konnte (SD Karte voll, SD Karte defekt, ...), würde ich gerne Eure Meinung haben, wie ich nach einem harten Reset die Ursache für das "Aufhängen" ermitteln kann. Ich würde auch gerne per WatchDog o.ä. schauen, dass das nicht mehr passiert - wenigstens ohne dass ich das merke.

Danke schon mal für Eure Vorschläge.

Gruß PeMue

andies · 13 Mai 2018, 20:07:01

SD Karte lesbar? Was sagen denn die Logs in /var/log? Ich hatte üblicherweise spannungsprobleme (keine 2A) oder (mehrfach!) kaputte SD. SanDisk hat die übrigens jedes Mal getauscht.

Gesendet von iPhone mit Tapatalk Pro

Wernieman · 14 Mai 2018, 15:52:27

Speziell beim Pi ist immer die Frage:
Genug Strom?
Was ist alles angeschlossen (USB)?

Ansonsten kann man nur die üblichen Verdächtigungen Kontrollieren, sprich /var/log/syslog kern.log etc.

PeMue · 20 Mai 2018, 14:20:50

Hallo zusammen,

Zitat von: Wernieman am 14 Mai 2018, 15:52:27
Was ist alles angeschlossen (USB)?

nur der WLAN Adapter und der Optolink Adapter (im Prinzip ein USB2seriell Konverter mit FTDI Chip).

Mittlerweile habe ich meinen WLAN Adapter im Verdacht (CSL 300 MBit/s).

syslog:

Code Auswählen

Apr 18 00:04:34 pmrpi01 wpa_supplicant[16877]: wlan0: WPA: Group rekeying completed with 9c:c7:a6:f8:ba:28 [GTK=TKIP]
Apr 18 00:14:34 pmrpi01 wpa_supplicant[16877]: wlan0: WPA: Group rekeying completed with 9c:c7:a6:f8:ba:28 [GTK=TKIP]
Apr 18 00:16:26 pmrpi01 kernel: [8469118.234297] hub 1-0:1.0: port 1 disabled by hub (EMI?), re-enabling...
Apr 18 00:16:26 pmrpi01 kernel: [8469118.234338] usb 1-1: USB disconnect, device number 37
Apr 18 00:16:26 pmrpi01 kernel: [8469118.234357] usb 1-1.1: USB disconnect, device number 38
Apr 18 00:16:26 pmrpi01 kernel: [8469118.234726] smsc95xx 1-1.1:1.0: eth0: unregister 'smsc95xx' usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet
Apr 18 00:16:26 pmrpi01 kernel: [8469118.254926] usb 1-1.2: USB disconnect, device number 39
Apr 18 00:16:26 pmrpi01 dhclient: receive_packet failed on wlan0: Network is down
Apr 18 00:16:26 pmrpi01 kernel: [8469118.475197] usb 1-1.3: USB disconnect, device number 40
Apr 18 00:16:26 pmrpi01 kernel: [8469118.479099] ftdi_sio ttyUSB0: FTDI USB Serial Device converter now disconnected from ttyUSB0
Apr 18 00:16:26 pmrpi01 kernel: [8469118.479176] ftdi_sio 1-1.3:1.0: device disconnected
Apr 18 00:16:26 pmrpi01 kernel: [8469118.634411] Indeed it is in host mode hprt0 = 00021501
Apr 18 00:16:26 pmrpi01 kernel: [8469118.994417] usb 1-1: new full-speed USB device number 41 using dwc_otg
Apr 18 00:16:26 pmrpi01 kernel: [8469118.994650] Indeed it is in host mode hprt0 = 00021501
Apr 18 00:16:27 pmrpi01 ifplugd(wlan0)[16858]: Link beat lost.
Apr 18 00:16:27 pmrpi01 ifplugd(eth0)[16658]: Exiting.
Apr 18 00:16:27 pmrpi01 dhclient: Internet Systems Consortium DHCP Client 4.2.2
Apr 18 00:16:27 pmrpi01 dhclient: Copyright 2004-2011 Internet Systems Consortium.
Apr 18 00:16:27 pmrpi01 dhclient: All rights reserved.
Apr 18 00:16:27 pmrpi01 dhclient: For info, please visit https://www.isc.org/software/dhcp/
Apr 18 00:16:27 pmrpi01 dhclient: 
Apr 18 00:16:27 pmrpi01 dhclient: Bind socket to interface: No such device
Apr 18 00:16:27 pmrpi01 kernel: [8469119.344643] usb 1-1: not running at top speed; connect to a high speed hub
Apr 18 00:16:27 pmrpi01 kernel: [8469119.344962] usb 1-1: New USB device found, idVendor=0424, idProduct=9512
Apr 18 00:16:27 pmrpi01 kernel: [8469119.344985] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 18 00:16:27 pmrpi01 kernel: [8469119.345863] hub 1-1:1.0: USB hub found
Apr 18 00:16:27 pmrpi01 kernel: [8469119.346048] hub 1-1:1.0: 3 ports detected
Apr 18 00:16:27 pmrpi01 ifplugd(wlan0)[16858]: Exiting.
Apr 18 00:16:27 pmrpi01 kernel: [8469119.624700] usb 1-1.1: new full-speed USB device number 42 using dwc_otg
Apr 18 00:16:27 pmrpi01 kernel: [8469119.725688] usb 1-1.1: not running at top speed; connect to a high speed hub
Apr 18 00:16:27 pmrpi01 kernel: [8469119.727720] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Apr 18 00:16:27 pmrpi01 kernel: [8469119.727744] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 18 00:16:27 pmrpi01 kernel: [8469119.738031] smsc95xx v1.0.4
Apr 18 00:16:27 pmrpi01 kernel: [8469119.845214] smsc95xx 1-1.1:1.0: eth0: register 'smsc95xx' at usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1b:89:2d
Apr 18 00:16:27 pmrpi01 kernel: [8469119.924727] usb 1-1.2: new full-speed USB device number 43 using dwc_otg
Apr 18 00:16:27 pmrpi01 ifplugd(eth0)[17244]: ifplugd 0.28 initializing.
Apr 18 00:16:27 pmrpi01 kernel: [8469120.025839] usb 1-1.2: not running at top speed; connect to a high speed hub
Apr 18 00:16:27 pmrpi01 kernel: [8469120.032035] usb 1-1.2: New USB device found, idVendor=0bda, idProduct=8172
Apr 18 00:16:27 pmrpi01 kernel: [8469120.032061] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Apr 18 00:16:27 pmrpi01 kernel: [8469120.032078] usb 1-1.2: Product: RTL8191S WLAN Adapter 
Apr 18 00:16:27 pmrpi01 kernel: [8469120.032092] usb 1-1.2: Manufacturer: Manufacturer Realtek 
Apr 18 00:16:27 pmrpi01 kernel: [8469120.032105] usb 1-1.2: SerialNumber: 00e04c000001
Apr 18 00:16:27 pmrpi01 kernel: [8469120.034587] r8712u: Staging version
Apr 18 00:16:27 pmrpi01 kernel: [8469120.034695] r8712u: register rtl8712_netdev_ops to netdev_ops
Apr 18 00:16:27 pmrpi01 kernel: [8469120.034717] r8712u: USB_SPEED_LOW with 4 endpoints
Apr 18 00:16:27 pmrpi01 kernel: [8469120.036970] r8712u: Boot from EFUSE: Autoload OK
Apr 18 00:16:28 pmrpi01 ifplugd(eth0)[17244]: Using interface eth0/B8:27:EB:1B:89:2D with driver <smsc95xx> (version: 22-Aug-2005)
Apr 18 00:16:28 pmrpi01 ifplugd(eth0)[17244]: Using detection mode: SIOCETHTOOL
Apr 18 00:16:28 pmrpi01 ifplugd(eth0)[17244]: Initialization complete, link beat not detected.
Apr 18 00:16:29 pmrpi01 ntpd[13205]: Deleting interface #6 wlan0, 192.168.188.46#123, interface stats: received=13, sent=13, dropped=0, active_time=5937 secs
Apr 18 00:16:29 pmrpi01 ntpd[13205]: 192.168.188.1 interface 192.168.188.46 -> (none)
Apr 18 00:16:29 pmrpi01 ntpd[13205]: peers refreshed
Apr 18 00:16:30 pmrpi01 kernel: [8469122.894935] r8712u: CustomerID = 0x000a
Apr 18 00:16:30 pmrpi01 kernel: [8469122.894967] r8712u: MAC Address from efuse = 24:05:0f:ac:79:1b
Apr 18 00:16:30 pmrpi01 kernel: [8469122.894981] r8712u: Loading firmware from "rtlwifi/rtl8712u.bin"
Apr 18 00:16:31 pmrpi01 ifplugd(wlan0)[17266]: ifplugd 0.28 initializing.
Apr 18 00:16:31 pmrpi01 kernel: [8469123.145491] usb 1-1.3: new full-speed USB device number 44 using dwc_otg
Apr 18 00:16:31 pmrpi01 kernel: [8469123.258157] usb 1-1.3: New USB device found, idVendor=0403, idProduct=6001
Apr 18 00:16:31 pmrpi01 kernel: [8469123.258188] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Apr 18 00:16:31 pmrpi01 kernel: [8469123.258206] usb 1-1.3: Product: FT232R USB UART
Apr 18 00:16:31 pmrpi01 kernel: [8469123.258220] usb 1-1.3: Manufacturer: FTDI
Apr 18 00:16:31 pmrpi01 kernel: [8469123.258232] usb 1-1.3: SerialNumber: AL02FEOW
Apr 18 00:16:31 pmrpi01 kernel: [8469123.263217] ftdi_sio 1-1.3:1.0: FTDI USB Serial Device converter detected
Apr 18 00:16:31 pmrpi01 kernel: [8469123.263388] usb 1-1.3: Detected FT232RL
Apr 18 00:16:31 pmrpi01 kernel: [8469123.263410] usb 1-1.3: Number of endpoints 2
Apr 18 00:16:31 pmrpi01 kernel: [8469123.263426] usb 1-1.3: Endpoint 1 MaxPacketSize 64
Apr 18 00:16:31 pmrpi01 kernel: [8469123.263441] usb 1-1.3: Endpoint 2 MaxPacketSize 64
Apr 18 00:16:31 pmrpi01 kernel: [8469123.263455] usb 1-1.3: Setting MaxPacketSize 64
Apr 18 00:16:31 pmrpi01 kernel: [8469123.267140] usb 1-1.3: FTDI USB Serial Device converter now attached to ttyUSB0
Apr 18 00:16:31 pmrpi01 wpa_supplicant[17282]: nl80211: 'nl80211' generic netlink not found
Apr 18 00:16:31 pmrpi01 wpa_supplicant[17282]: Failed to initialize driver 'nl80211'
Apr 18 00:16:31 pmrpi01 wpa_supplicant[17282]: rfkill: Cannot open RFKILL control device
Apr 18 00:16:31 pmrpi01 kernel: [8469123.856013] r8712u: 1 RCR=0x153f00e
Apr 18 00:16:31 pmrpi01 kernel: [8469123.858973] r8712u: 2 RCR=0x553f00e
Apr 18 00:16:31 pmrpi01 ifplugd(wlan0)[17266]: Using interface wlan0/24:05:0F:AC:79:1B with driver <r8712u> (version: )
Apr 18 00:16:31 pmrpi01 ifplugd(wlan0)[17266]: Using detection mode: wireless extension
Apr 18 00:16:31 pmrpi01 ifplugd(wlan0)[17266]: Initialization complete, link beat not detected.
Apr 18 00:16:31 pmrpi01 wpa_supplicant[17282]: ctrl_iface exists and seems to be in use - cannot override it
Apr 18 00:16:31 pmrpi01 wpa_supplicant[17282]: Delete '/var/run/wpa_supplicant/wlan0' manually if it is not used anymore
Apr 18 00:16:32 pmrpi01 wpa_supplicant[17282]: Failed to initialize control interface 'DIR=/var/run/wpa_supplicant GROUP=netdev'.#012You may have another wpa_supplicant process already running or the file was#012left by an unclean termination of wpa_supplicant in which case you will need#012to manually remove this file before starting wpa_supplicant again.
Apr 18 00:16:32 pmrpi01 wpa_supplicant[16877]: wlan0: CTRL-EVENT-TERMINATING - signal 15 received
Apr 18 00:16:32 pmrpi01 dhclient: Internet Systems Consortium DHCP Client 4.2.2
Apr 18 00:16:32 pmrpi01 dhclient: Copyright 2004-2011 Internet Systems Consortium.
Apr 18 00:16:32 pmrpi01 dhclient: All rights reserved.
Apr 18 00:16:32 pmrpi01 dhclient: For info, please visit https://www.isc.org/software/dhcp/
Apr 18 00:16:32 pmrpi01 dhclient: 
Apr 18 00:16:32 pmrpi01 dhclient: Listening on LPF/wlan0/24:05:0f:ac:79:1b
Apr 18 00:16:32 pmrpi01 dhclient: Sending on   LPF/wlan0/24:05:0f:ac:79:1b
Apr 18 00:16:32 pmrpi01 dhclient: Sending on   Socket/fallback
Apr 18 00:16:32 pmrpi01 dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Apr 18 00:16:39 pmrpi01 dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Apr 18 00:16:46 pmrpi01 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 8
Apr 18 00:16:54 pmrpi01 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 14
Apr 18 00:17:01 pmrpi01 /USR/SBIN/CRON[17303]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Apr 18 00:17:08 pmrpi01 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 16
Apr 18 00:17:24 pmrpi01 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 14
Apr 18 00:17:38 pmrpi01 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 7
Apr 18 00:17:45 pmrpi01 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 2
Apr 18 00:17:47 pmrpi01 dhclient: No DHCPOFFERS received.
Apr 18 00:17:47 pmrpi01 dhclient: Trying recorded lease 192.168.188.46
Apr 18 00:17:49 pmrpi01 ntpd[13205]: Listen normally on 7 wlan0 192.168.188.46 UDP 123
Apr 18 00:17:49 pmrpi01 ntpd[13205]: peers refreshed
Apr 18 00:17:50 pmrpi01 dhclient: Unable to obtain a lease on first try.  Exiting.
Apr 18 00:17:52 pmrpi01 ntpd[13205]: Deleting interface #7 wlan0, 192.168.188.46#123, interface stats: received=0, sent=1, dropped=0, active_time=3 secs
Apr 18 00:17:52 pmrpi01 ntpd[13205]: 192.168.188.1 interface 192.168.188.46 -> (none)
Apr 18 00:17:52 pmrpi01 ntpd[13205]: peers refreshed
Apr 18 00:37:55 pmrpi01 kernel: [8470407.784086] hub 1-0:1.0: port 1 disabled by hub (EMI?), re-enabling...
Apr 18 00:37:55 pmrpi01 kernel: [8470407.784127] usb 1-1: USB disconnect, device number 41
Apr 18 00:37:55 pmrpi01 kernel: [8470407.784145] usb 1-1.1: USB disconnect, device number 42
Apr 18 00:37:55 pmrpi01 kernel: [8470407.784515] smsc95xx 1-1.1:1.0: eth0: unregister 'smsc95xx' usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet
Apr 18 00:38:00 pmrpi01 ifplugd(eth0)[17244]: Link beat detected.
Apr 18 00:38:00 pmrpi01 kernel: [8470412.774255] smsc95xx 1-1.1:1.0: eth0: Failed to read register index 0x00000114
Apr 18 00:38:00 pmrpi01 kernel: [8470412.774287] smsc95xx 1-1.1:1.0: eth0: Error reading MII_ACCESS
Apr 18 00:38:00 pmrpi01 kernel: [8470412.774305] smsc95xx 1-1.1:1.0: eth0: MII is busy in smsc95xx_mdio_read
Apr 18 00:38:00 pmrpi01 kernel: [8470412.774329] smsc95xx 1-1.1:1.0: eth0: Failed to read register index 0x00000114
Apr 18 00:38:00 pmrpi01 kernel: [8470412.774344] smsc95xx 1-1.1:1.0: eth0: Error reading MII_ACCESS
Apr 18 00:38:00 pmrpi01 kernel: [8470412.774357] smsc95xx 1-1.1:1.0: eth0: MII is busy in smsc95xx_mdio_read
Apr 18 00:38:00 pmrpi01 kernel: [8470412.786198] usb 1-1.2: USB disconnect, device number 43
Apr 18 00:38:00 pmrpi01 ifplugd(eth0)[17244]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Apr 18 00:38:00 pmrpi01 ifplugd(eth0)[17244]: Killing child.
Apr 18 00:38:00 pmrpi01 ifplugd(eth0)[17244]: Killing child.
Apr 18 00:38:00 pmrpi01 kernel: [8470413.005134] usb 1-1.3: USB disconnect, device number 44
Apr 18 00:38:00 pmrpi01 kernel: [8470413.009074] ftdi_sio ttyUSB0: FTDI USB Serial Device converter now disconnected from ttyUSB0
Apr 18 00:38:00 pmrpi01 kernel: [8470413.009151] ftdi_sio 1-1.3:1.0: device disconnected
Apr 18 00:38:00 pmrpi01 dhclient: Internet Systems Consortium DHCP Client 4.2.2
Apr 18 00:38:00 pmrpi01 ifplugd(eth0)[17244]: client: Internet Systems Consortium DHCP Client 4.2.2
Apr 18 00:38:00 pmrpi01 dhclient: Copyright 2004-2011 Internet Systems Consortium.
Apr 18 00:38:00 pmrpi01 ifplugd(eth0)[17244]: client: Copyright 2004-2011 Internet Systems Consortium.
Apr 18 00:38:00 pmrpi01 dhclient: All rights reserved.
Apr 18 00:38:00 pmrpi01 ifplugd(eth0)[17244]: client: All rights reserved.
Apr 18 00:38:00 pmrpi01 dhclient: For info, please visit https://www.isc.org/software/dhcp/
Apr 18 00:38:00 pmrpi01 ifplugd(eth0)[17244]: client: For info, please visit https://www.isc.org/software/dhcp/
Apr 18 00:38:00 pmrpi01 dhclient: 
Apr 18 00:38:01 pmrpi01 kernel: [8470413.164387] Indeed it is in host mode hprt0 = 00021501
Apr 18 00:38:01 pmrpi01 ifplugd(eth0)[17244]: client: Cannot find device "eth0"
Apr 18 00:38:01 pmrpi01 kernel: [8470413.494387] usb 1-1: new full-speed USB device number 45 using dwc_otg
Apr 18 00:38:01 pmrpi01 kernel: [8470413.494647] Indeed it is in host mode hprt0 = 00021501
Apr 18 00:38:01 pmrpi01 dhclient: Bind socket to interface: No such device
Apr 18 00:38:01 pmrpi01 ifplugd(eth0)[17244]: client: Bind socket to interface: No such device
Apr 18 00:38:01 pmrpi01 ifplugd(eth0)[17244]: client: Failed to bring up eth0.
Apr 18 00:38:01 pmrpi01 ifplugd(eth0)[17244]: Program execution failed, return value is 0.
Apr 18 00:38:01 pmrpi01 ifplugd(wlan0)[17266]: Exiting.
Apr 18 00:38:01 pmrpi01 kernel: [8470413.774668] usb 1-1: not running at top speed; connect to a high speed hub
Apr 18 00:38:01 pmrpi01 kernel: [8470413.774992] usb 1-1: New USB device found, idVendor=0424, idProduct=9512
Apr 18 00:38:01 pmrpi01 kernel: [8470413.775017] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 18 00:38:01 pmrpi01 kernel: [8470413.779509] hub 1-1:1.0: USB hub found
Apr 18 00:38:01 pmrpi01 kernel: [8470413.779740] hub 1-1:1.0: 3 ports detected
Apr 18 00:38:01 pmrpi01 kernel: [8470414.054978] usb 1-1.1: new full-speed USB device number 46 using dwc_otg
Apr 18 00:38:02 pmrpi01 kernel: [8470414.155989] usb 1-1.1: not running at top speed; connect to a high speed hub
Apr 18 00:38:02 pmrpi01 kernel: [8470414.158012] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Apr 18 00:38:02 pmrpi01 kernel: [8470414.158035] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 18 00:38:02 pmrpi01 kernel: [8470414.161355] smsc95xx v1.0.4
Apr 18 00:38:02 pmrpi01 kernel: [8470414.265380] smsc95xx 1-1.1:1.0: eth0: register 'smsc95xx' at usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1b:89:2d
Apr 18 00:38:02 pmrpi01 kernel: [8470414.345020] usb 1-1.2: new full-speed USB device number 47 using dwc_otg
Apr 18 00:38:02 pmrpi01 kernel: [8470414.446104] usb 1-1.2: not running at top speed; connect to a high speed hub
Apr 18 00:38:02 pmrpi01 kernel: [8470414.452244] usb 1-1.2: New USB device found, idVendor=0bda, idProduct=8172
Apr 18 00:38:02 pmrpi01 kernel: [8470414.452269] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Apr 18 00:38:02 pmrpi01 kernel: [8470414.452287] usb 1-1.2: Product: RTL8191S WLAN Adapter 
Apr 18 00:38:02 pmrpi01 kernel: [8470414.452305] usb 1-1.2: Manufacturer: Manufacturer Realtek 
Apr 18 00:38:02 pmrpi01 kernel: [8470414.452318] usb 1-1.2: SerialNumber: 00e04c000001
Apr 18 00:38:02 pmrpi01 kernel: [8470414.453627] r8712u: Staging version
Apr 18 00:38:02 pmrpi01 kernel: [8470414.453731] r8712u: register rtl8712_netdev_ops to netdev_ops
Apr 18 00:38:02 pmrpi01 kernel: [8470414.453752] r8712u: USB_SPEED_LOW with 4 endpoints
Apr 18 00:38:02 pmrpi01 kernel: [8470414.456080] r8712u: Boot from EFUSE: Autoload OK
Apr 18 00:38:02 pmrpi01 ifplugd(eth0)[17244]: Link beat lost.
Apr 18 00:38:05 pmrpi01 kernel: [8470417.314227] r8712u: CustomerID = 0x000a
Apr 18 00:38:05 pmrpi01 kernel: [8470417.314259] r8712u: MAC Address from efuse = 24:05:0f:ac:79:1b
Apr 18 00:38:05 pmrpi01 kernel: [8470417.314273] r8712u: Loading firmware from "rtlwifi/rtl8712u.bin"
Apr 18 00:38:05 pmrpi01 ifplugd(wlan0)[17444]: ifplugd 0.28 initializing.
Apr 18 00:38:05 pmrpi01 kernel: [8470417.565186] usb 1-1.3: new full-speed USB device number 48 using dwc_otg
Apr 18 00:38:05 pmrpi01 kernel: [8470417.684280] usb 1-1.3: New USB device found, idVendor=0403, idProduct=6001
Apr 18 00:38:05 pmrpi01 kernel: [8470417.684313] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Apr 18 00:38:05 pmrpi01 kernel: [8470417.684329] usb 1-1.3: Product: FT232R USB UART
Apr 18 00:38:05 pmrpi01 kernel: [8470417.684468] usb 1-1.3: Manufacturer: FTDI
Apr 18 00:38:05 pmrpi01 kernel: [8470417.684489] usb 1-1.3: SerialNumber: AL02FEOW
Apr 18 00:38:05 pmrpi01 kernel: [8470417.692612] ftdi_sio 1-1.3:1.0: FTDI USB Serial Device converter detected
Apr 18 00:38:05 pmrpi01 kernel: [8470417.692784] usb 1-1.3: Detected FT232RL
Apr 18 00:38:05 pmrpi01 kernel: [8470417.692806] usb 1-1.3: Number of endpoints 2
Apr 18 00:38:05 pmrpi01 kernel: [8470417.692823] usb 1-1.3: Endpoint 1 MaxPacketSize 64
Apr 18 00:38:05 pmrpi01 kernel: [8470417.692837] usb 1-1.3: Endpoint 2 MaxPacketSize 64
Apr 18 00:38:05 pmrpi01 kernel: [8470417.692851] usb 1-1.3: Setting MaxPacketSize 64
Apr 18 00:38:05 pmrpi01 kernel: [8470417.696162] usb 1-1.3: FTDI USB Serial Device converter now attached to ttyUSB0
Apr 18 00:38:05 pmrpi01 wpa_supplicant[17455]: nl80211: 'nl80211' generic netlink not found
Apr 18 00:38:05 pmrpi01 wpa_supplicant[17455]: Failed to initialize driver 'nl80211'
Apr 18 00:38:05 pmrpi01 wpa_supplicant[17455]: rfkill: Cannot open RFKILL control device
Apr 18 00:38:06 pmrpi01 kernel: [8470418.466290] r8712u: 1 RCR=0x153f00e
Apr 18 00:38:06 pmrpi01 kernel: [8470418.469248] r8712u: 2 RCR=0x553f00e
Apr 18 00:38:06 pmrpi01 ifplugd(wlan0)[17444]: Using interface wlan0/24:05:0F:AC:79:1B with driver <r8712u> (version: )
Apr 18 00:38:06 pmrpi01 ifplugd(wlan0)[17444]: Using detection mode: wireless extension
Apr 18 00:38:06 pmrpi01 ifplugd(wlan0)[17444]: Initialization complete, link beat not detected.
Apr 18 00:38:07 pmrpi01 dhclient: Internet Systems Consortium DHCP Client 4.2.2
Apr 18 00:38:07 pmrpi01 dhclient: Copyright 2004-2011 Internet Systems Consortium.
Apr 18 00:38:07 pmrpi01 dhclient: All rights reserved.
Apr 18 00:38:07 pmrpi01 dhclient: For info, please visit https://www.isc.org/software/dhcp/
Apr 18 00:38:07 pmrpi01 dhclient: 
Apr 18 00:38:07 pmrpi01 dhclient: Listening on LPF/wlan0/24:05:0f:ac:79:1b
Apr 18 00:38:07 pmrpi01 dhclient: Sending on   LPF/wlan0/24:05:0f:ac:79:1b
Apr 18 00:38:07 pmrpi01 dhclient: Sending on   Socket/fallback
Apr 18 00:38:07 pmrpi01 dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Apr 18 00:38:12 pmrpi01 ifplugd(eth0)[17244]: Executing '/etc/ifplugd/ifplugd.action eth0 down'.
Apr 18 00:38:12 pmrpi01 ifplugd(eth0)[17244]: client: /sbin/ifdown: interface eth0 not configured
Apr 18 00:38:12 pmrpi01 ifplugd(eth0)[17244]: Program executed successfully.
Apr 18 00:38:14 pmrpi01 dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Apr 18 00:38:16 pmrpi01 wpa_supplicant[17463]: wlan0: Trying to associate with 9c:c7:a6:f8:ba:28 (SSID='PMWLAN02' freq=2427 MHz)
Apr 18 00:38:16 pmrpi01 wpa_supplicant[17463]: wlan0: Association request to the driver failed
Apr 18 00:38:16 pmrpi01 wpa_supplicant[17463]: wlan0: Associated with 9c:c7:a6:f8:ba:28
Apr 18 00:38:17 pmrpi01 wpa_supplicant[17463]: wlan0: WPA: Key negotiation completed with 9c:c7:a6:f8:ba:28 [PTK=TKIP GTK=TKIP]
Apr 18 00:38:17 pmrpi01 wpa_supplicant[17463]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c7:a6:f8:ba:28 completed (auth) [id=0 id_str=]
Apr 18 00:38:17 pmrpi01 ifplugd(wlan0)[17444]: Link beat detected.
Apr 18 00:38:17 pmrpi01 ifplugd(wlan0)[17444]: Executing '/etc/ifplugd/ifplugd.action wlan0 up'.
Apr 18 00:38:17 pmrpi01 ifplugd(wlan0)[17444]: client: /sbin/ifup: interface wlan0 already configured
Apr 18 00:38:17 pmrpi01 ifplugd(wlan0)[17444]: Program executed successfully.
Apr 18 00:38:18 pmrpi01 wpa_supplicant[17463]: wlan0: WPA: Group rekeying completed with 9c:c7:a6:f8:ba:28 [GTK=TKIP]
Apr 18 00:38:19 pmrpi01 wpa_supplicant[17463]: wlan0: WPA: Group rekeying completed with 9c:c7:a6:f8:ba:28 [GTK=TKIP]
Apr 18 00:38:25 pmrpi01 dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 7
Apr 18 00:38:26 pmrpi01 dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Apr 18 00:38:26 pmrpi01 dhclient: DHCPOFFER from 192.168.188.1
Apr 18 00:38:26 pmrpi01 dhclient: DHCPACK from 192.168.188.1
Apr 18 00:38:27 pmrpi01 dhclient: bound to 192.168.188.46 -- renewal in 361981 seconds.
Apr 18 00:38:28 pmrpi01 ntpd[13205]: Listen normally on 8 wlan0 192.168.188.46 UDP 123
Apr 18 00:38:28 pmrpi01 ntpd[13205]: peers refreshed
Apr 18 00:44:34 pmrpi01 wpa_supplicant[17463]: wlan0: WPA: Group rekeying completed with 9c:c7:a6:f8:ba:28 [GTK=TKIP]
Apr 18 00:17:39 pmrpi01 kernel: imklog 5.8.11, log source = /proc/kmsg started.
Apr 18 00:17:39 pmrpi01 rsyslogd: [origin software="rsyslogd" swVersion="5.8.11" x-pid="1918" x-info="http://www.rsyslog.com"] start
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Booting Linux on physical CPU 0
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Initializing cgroup subsys cpu
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Linux version 3.6.11+ (dc4@dc4-arm-01) (gcc version 4.7.2 20120731 (prerelease) (crosstool-NG linaro-1.13.1+bzr2458 - Linaro GCC 2012.08) ) #371 PREEMPT Thu Feb 7 16:31:35 GMT 2013
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Machine: BCM2708
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] cma: CMA: reserved 16 MiB at 1e000000
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Memory policy: ECC disabled, Data cache writeback
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] On node 0 totalpages: 126976
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] free_area_init_node: node 0, pgdat c053b834, node_mem_map c05e5000
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]   Normal zone: 992 pages used for memmap
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]   Normal zone: 0 pages reserved
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]   Normal zone: 125984 pages, LIFO batch:31
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] pcpu-alloc: [0] 0 
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 125984
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Kernel command line: dma.dmachans=0x7f35 bcm2708_fb.fbwidth=1280 bcm2708_fb.fbheight=720 bcm2708.boardrev=0xe bcm2708.serial=0xaa1b892d smsc95xx.macaddr=B8:27:EB:1B:89:2D sdhci-bcm2708.emmc_clock_freq=100000000 vc_mem.mem_base=0x1fa00000 vc_mem.mem_size=0x20000000  dwc_otg.lpm_enable=0 dwc_otg.speed=1 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline rootwait
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Memory: 496MB = 496MB total
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Memory: 481032k/481032k available, 26872k reserved, 0K highmem
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Virtual kernel memory layout:
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]     fixmap  : 0xfff00000 - 0xfffe0000   ( 896 kB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]     vmalloc : 0xdf800000 - 0xff000000   ( 504 MB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xdf000000   ( 496 MB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]       .text : 0xc0008000 - 0xc04e5470   (4982 kB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]       .init : 0xc04e6000 - 0xc0506f24   ( 132 kB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]       .data : 0xc0508000 - 0xc053c060   ( 209 kB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000]        .bss : 0xc053c084 - 0xc05e4738   ( 674 kB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] NR_IRQS:330
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 4294967ms
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] Console: colour dummy device 80x30
Apr 18 00:17:39 pmrpi01 kernel: [    0.000000] console [tty1] enabled
Apr 18 00:17:39 pmrpi01 kernel: [    0.001000] Calibrating delay loop... 464.48 BogoMIPS (lpj=2322432)
Apr 18 00:17:39 pmrpi01 kernel: [    0.060062] pid_max: default: 32768 minimum: 301
Apr 18 00:17:39 pmrpi01 kernel: [    0.060416] Mount-cache hash table entries: 512
Apr 18 00:17:39 pmrpi01 kernel: [    0.061182] Initializing cgroup subsys cpuacct
Apr 18 00:17:39 pmrpi01 kernel: [    0.061238] Initializing cgroup subsys devices
Apr 18 00:17:39 pmrpi01 kernel: [    0.061272] Initializing cgroup subsys freezer
Apr 18 00:17:39 pmrpi01 kernel: [    0.061301] Initializing cgroup subsys blkio
Apr 18 00:17:39 pmrpi01 kernel: [    0.061393] CPU: Testing write buffer coherency: ok
Apr 18 00:17:39 pmrpi01 kernel: [    0.061728] hw perfevents: enabled with v6 PMU driver, 3 counters available
Apr 18 00:17:39 pmrpi01 kernel: [    0.061871] Setting up static identity map for 0x39d3f8 - 0x39d454
Apr 18 00:17:39 pmrpi01 kernel: [    0.063377] devtmpfs: initialized
Apr 18 00:17:39 pmrpi01 kernel: [    0.074105] NET: Registered protocol family 16
Apr 18 00:17:39 pmrpi01 kernel: [    0.080583] DMA: preallocated 4096 KiB pool for atomic coherent allocations
Apr 18 00:17:39 pmrpi01 kernel: [    0.081661] bcm2708.uart_clock = 0
Apr 18 00:17:39 pmrpi01 kernel: [    0.083023] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
Apr 18 00:17:39 pmrpi01 kernel: [    0.083077] hw-breakpoint: maximum watchpoint size is 4 bytes.
Apr 18 00:17:39 pmrpi01 kernel: [    0.083113] mailbox: Broadcom VideoCore Mailbox driver
Apr 18 00:17:39 pmrpi01 kernel: [    0.083206] bcm2708_vcio: mailbox at f200b880
Apr 18 00:17:39 pmrpi01 kernel: [    0.083307] bcm_power: Broadcom power driver
Apr 18 00:17:39 pmrpi01 kernel: [    0.083344] bcm_power_open() -> 0
Apr 18 00:17:39 pmrpi01 kernel: [    0.083369] bcm_power_request(0, 8)
Apr 18 00:17:39 pmrpi01 kernel: [    0.584056] bcm_mailbox_read -> 00000080, 0
Apr 18 00:17:39 pmrpi01 kernel: [    0.584096] bcm_power_request -> 0
Apr 18 00:17:39 pmrpi01 kernel: [    0.584123] Serial: AMBA PL011 UART driver
Apr 18 00:17:39 pmrpi01 kernel: [    0.584268] dev:f1: ttyAMA0 at MMIO 0x20201000 (irq = 83) is a PL011 rev3
Apr 18 00:17:39 pmrpi01 kernel: [    0.604119] bio: create slab <bio-0> at 0
Apr 18 00:17:39 pmrpi01 kernel: [    0.605030] SCSI subsystem initialized
Apr 18 00:17:39 pmrpi01 kernel: [    0.605286] usbcore: registered new interface driver usbfs
Apr 18 00:17:39 pmrpi01 kernel: [    0.605400] usbcore: registered new interface driver hub
Apr 18 00:17:39 pmrpi01 kernel: [    0.605682] usbcore: registered new device driver usb
Apr 18 00:17:39 pmrpi01 kernel: [    0.606965] Switching to clocksource stc
Apr 18 00:17:39 pmrpi01 kernel: [    0.607207] FS-Cache: Loaded
Apr 18 00:17:39 pmrpi01 kernel: [    0.607490] CacheFiles: Loaded
Apr 18 00:17:39 pmrpi01 kernel: [    0.619334] NET: Registered protocol family 2
Apr 18 00:17:39 pmrpi01 kernel: [    0.620210] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
Apr 18 00:17:39 pmrpi01 kernel: [    0.620693] TCP bind hash table entries: 16384 (order: 4, 65536 bytes)
Apr 18 00:17:39 pmrpi01 kernel: [    0.620976] TCP: Hash tables configured (established 16384 bind 16384)
Apr 18 00:17:39 pmrpi01 kernel: [    0.621044] TCP: reno registered
Apr 18 00:17:39 pmrpi01 kernel: [    0.621080] UDP hash table entries: 256 (order: 0, 4096 bytes)
Apr 18 00:17:39 pmrpi01 kernel: [    0.621134] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Apr 18 00:17:39 pmrpi01 kernel: [    0.621426] NET: Registered protocol family 1
Apr 18 00:17:39 pmrpi01 kernel: [    0.621951] RPC: Registered named UNIX socket transport module.
Apr 18 00:17:39 pmrpi01 kernel: [    0.622008] RPC: Registered udp transport module.
Apr 18 00:17:39 pmrpi01 kernel: [    0.622040] RPC: Registered tcp transport module.
Apr 18 00:17:39 pmrpi01 kernel: [    0.622069] RPC: Registered tcp NFSv4.1 backchannel transport module.
Apr 18 00:17:39 pmrpi01 kernel: [    0.622732] bcm2708_dma: DMA manager at f2007000
Apr 18 00:17:39 pmrpi01 kernel: [    0.622861] bcm2708_gpio: bcm2708_gpio_probe c0515d98
Apr 18 00:17:39 pmrpi01 kernel: [    0.623287] vc-mem: phys_addr:0x00000000 mem_base=0x1fa00000 mem_size:0x20000000(512 MiB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.624230] audit: initializing netlink socket (disabled)
Apr 18 00:17:39 pmrpi01 kernel: [    0.624330] type=2000 audit(0.600:1): initialized
Apr 18 00:17:39 pmrpi01 kernel: [    0.743053] VFS: Disk quotas dquot_6.5.2
Apr 18 00:17:39 pmrpi01 kernel: [    0.743184] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Apr 18 00:17:39 pmrpi01 kernel: [    0.743621] FS-Cache: Netfs 'nfs' registered for caching
Apr 18 00:17:39 pmrpi01 kernel: [    0.744025] NFS: Registering the id_resolver key type
Apr 18 00:17:39 pmrpi01 kernel: [    0.744154] Key type id_resolver registered
Apr 18 00:17:39 pmrpi01 kernel: [    0.744191] Key type id_legacy registered
Apr 18 00:17:39 pmrpi01 kernel: [    0.744558] msgmni has been set to 971
Apr 18 00:17:39 pmrpi01 kernel: [    0.746297] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
Apr 18 00:17:39 pmrpi01 kernel: [    0.746607] io scheduler noop registered
Apr 18 00:17:39 pmrpi01 kernel: [    0.746650] io scheduler deadline registered (default)
Apr 18 00:17:39 pmrpi01 kernel: [    0.746705] io scheduler cfq registered
Apr 18 00:17:39 pmrpi01 kernel: [    0.771932] Console: switching to colour frame buffer device 160x45
Apr 18 00:17:39 pmrpi01 kernel: [    0.782997] vc-cma: Videocore CMA driver
Apr 18 00:17:39 pmrpi01 kernel: [    0.783139] vc-cma: vc_cma_base      = 0x00000000
Apr 18 00:17:39 pmrpi01 kernel: [    0.783260] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.783392] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
Apr 18 00:17:39 pmrpi01 kernel: [    0.792538] brd: module loaded
Apr 18 00:17:39 pmrpi01 kernel: [    0.797653] loop: module loaded
Apr 18 00:17:39 pmrpi01 kernel: [    0.798122] vchiq: vchiq_init_state: slot_zero = 0xde000000, is_master = 0
Apr 18 00:17:39 pmrpi01 kernel: [    0.799040] Loading iSCSI transport class v2.0-870.
Apr 18 00:17:39 pmrpi01 kernel: [    0.799999] usbcore: registered new interface driver smsc95xx
Apr 18 00:17:39 pmrpi01 kernel: [    0.800279] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Apr 18 00:17:39 pmrpi01 kernel: [    1.009188] Core Release: 2.80a
Apr 18 00:17:39 pmrpi01 kernel: [    1.012827] Setting default values for core params
Apr 18 00:17:39 pmrpi01 kernel: [    1.016379] Finished setting default values for core params
Apr 18 00:17:39 pmrpi01 kernel: [    1.225068] Using Buffer DMA mode
Apr 18 00:17:39 pmrpi01 kernel: [    1.228660] Periodic Transfer Interrupt Enhancement - disabled
Apr 18 00:17:39 pmrpi01 kernel: [    1.232191] Multiprocessor Interrupt Enhancement - disabled
Apr 18 00:17:39 pmrpi01 kernel: [    1.235706] OTG VER PARAM: 0, OTG VER FLAG: 0
Apr 18 00:17:39 pmrpi01 kernel: [    1.239245] Dedicated Tx FIFOs mode
Apr 18 00:17:39 pmrpi01 kernel: [    1.243768] dwc_otg: Microframe scheduler enabled
Apr 18 00:17:39 pmrpi01 kernel: [    1.244194] dwc_otg bcm2708_usb: DWC OTG Controller
Apr 18 00:17:39 pmrpi01 kernel: [    1.247995] dwc_otg bcm2708_usb: new USB bus registered, assigned bus number 1
Apr 18 00:17:39 pmrpi01 kernel: [    1.251753] dwc_otg bcm2708_usb: irq 32, io mem 0x00000000
Apr 18 00:17:39 pmrpi01 kernel: [    1.255423] Init: Port Power? op_state=1
Apr 18 00:17:39 pmrpi01 kernel: [    1.259043] Init: Power Port (0)
Apr 18 00:17:39 pmrpi01 kernel: [    1.262650] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Apr 18 00:17:39 pmrpi01 kernel: [    1.266222] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Apr 18 00:17:39 pmrpi01 kernel: [    1.269807] usb usb1: Product: DWC OTG Controller
Apr 18 00:17:39 pmrpi01 kernel: [    1.273332] usb usb1: Manufacturer: Linux 3.6.11+ dwc_otg_hcd
Apr 18 00:17:39 pmrpi01 kernel: [    1.276858] usb usb1: SerialNumber: bcm2708_usb
Apr 18 00:17:39 pmrpi01 kernel: [    1.281143] hub 1-0:1.0: USB hub found
Apr 18 00:17:39 pmrpi01 kernel: [    1.284688] hub 1-0:1.0: 1 port detected
Apr 18 00:17:39 pmrpi01 kernel: [    1.288495] dwc_otg: FIQ enabled
Apr 18 00:17:39 pmrpi01 kernel: [    1.288516] dwc_otg: NAK holdoff enabled
Apr 18 00:17:39 pmrpi01 kernel: [    1.288536] Module dwc_common_port init
Apr 18 00:17:39 pmrpi01 kernel: [    1.288762] Initializing USB Mass Storage driver...
Apr 18 00:17:39 pmrpi01 kernel: [    1.292447] usbcore: registered new interface driver usb-storage
Apr 18 00:17:39 pmrpi01 kernel: [    1.295949] USB Mass Storage support registered.
Apr 18 00:17:39 pmrpi01 kernel: [    1.299696] usbcore: registered new interface driver libusual
Apr 18 00:17:39 pmrpi01 kernel: [    1.303521] mousedev: PS/2 mouse device common for all mice
Apr 18 00:17:39 pmrpi01 kernel: [    1.307788] bcm2835-cpufreq: min=700000 max=700000 cur=700000
Apr 18 00:17:39 pmrpi01 kernel: [    1.308055] bcm2835-cpufreq: switching to governor powersavebcm2835-cpufreq: switching to governor powersave
Apr 18 00:17:39 pmrpi01 kernel: [    1.311707] cpuidle: using governor ladder
Apr 18 00:17:39 pmrpi01 kernel: [    1.318775] cpuidle: using governor menu
Apr 18 00:17:39 pmrpi01 kernel: [    1.322404] sdhci: Secure Digital Host Controller Interface driver
Apr 18 00:17:39 pmrpi01 kernel: [    1.326005] sdhci: Copyright(c) Pierre Ossman
Apr 18 00:17:39 pmrpi01 kernel: [    1.329707] sdhci: Enable low-latency mode
Apr 18 00:17:39 pmrpi01 kernel: [    1.377260] mmc0: SDHCI controller on BCM2708_Arasan [platform] using platform's DMA
Apr 18 00:17:39 pmrpi01 kernel: [    1.381181] mmc0: BCM2708 SDHC host at 0x20300000 DMA 2 IRQ 77
Apr 18 00:17:39 pmrpi01 kernel: [    1.385015] sdhci-pltfm: SDHCI platform and OF driver helper
Apr 18 00:17:39 pmrpi01 kernel: [    1.391668] usbcore: registered new interface driver usbhid
Apr 18 00:17:39 pmrpi01 kernel: [    1.398724] usbhid: USB HID core driver
Apr 18 00:17:39 pmrpi01 kernel: [    1.405254] TCP: cubic registered
Apr 18 00:17:39 pmrpi01 kernel: [    1.411182] Initializing XFRM netlink socket
Apr 18 00:17:39 pmrpi01 kernel: [    1.417091] NET: Registered protocol family 17
Apr 18 00:17:39 pmrpi01 kernel: [    1.423084] Key type dns_resolver registered
Apr 18 00:17:39 pmrpi01 kernel: [    1.429393] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
Apr 18 00:17:39 pmrpi01 kernel: [    1.437474] registered taskstats version 1
Apr 18 00:17:39 pmrpi01 kernel: [    1.442317] Waiting for root device /dev/mmcblk0p2...
Apr 18 00:17:39 pmrpi01 kernel: [    1.487184] Indeed it is in host mode hprt0 = 00021501
Apr 18 00:17:39 pmrpi01 kernel: [    1.579416] mmc0: could read SD Status register (SSR) at the 8th attempt
Apr 18 00:17:39 pmrpi01 kernel: [    1.587778] mmc0: new high speed SDHC card at address 0007
Apr 18 00:17:39 pmrpi01 kernel: [    1.594349] mmcblk0: mmc0:0007 SD04G 3.70 GiB 
Apr 18 00:17:39 pmrpi01 kernel: [    1.600546]  mmcblk0: p1 p2
Apr 18 00:17:39 pmrpi01 kernel: [    1.707137] usb 1-1: new full-speed USB device number 2 using dwc_otg
Apr 18 00:17:39 pmrpi01 kernel: [    1.711329] Indeed it is in host mode hprt0 = 00021501
Apr 18 00:17:39 pmrpi01 kernel: [    1.927418] usb 1-1: not running at top speed; connect to a high speed hub
Apr 18 00:17:39 pmrpi01 kernel: [    1.931810] usb 1-1: New USB device found, idVendor=0424, idProduct=9512
Apr 18 00:17:39 pmrpi01 kernel: [    1.935720] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 18 00:17:39 pmrpi01 kernel: [    1.940471] hub 1-1:1.0: USB hub found
Apr 18 00:17:39 pmrpi01 kernel: [    1.944571] hub 1-1:1.0: 3 ports detected
Apr 18 00:17:39 pmrpi01 kernel: [    2.227285] usb 1-1.1: new full-speed USB device number 3 using dwc_otg
Apr 18 00:17:39 pmrpi01 kernel: [    2.348306] usb 1-1.1: not running at top speed; connect to a high speed hub
Apr 18 00:17:39 pmrpi01 kernel: [    2.354351] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Apr 18 00:17:39 pmrpi01 kernel: [    2.358405] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 18 00:17:39 pmrpi01 kernel: [    2.365564] smsc95xx v1.0.4
Apr 18 00:17:39 pmrpi01 kernel: [    2.486649] smsc95xx 1-1.1:1.0: eth0: register 'smsc95xx' at usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1b:89:2d
Apr 18 00:17:39 pmrpi01 kernel: [    2.587307] usb 1-1.2: new full-speed USB device number 4 using dwc_otg
Apr 18 00:17:39 pmrpi01 kernel: [    2.708479] usb 1-1.2: not running at top speed; connect to a high speed hub
Apr 18 00:17:39 pmrpi01 kernel: [    2.718588] usb 1-1.2: New USB device found, idVendor=0bda, idProduct=8172
Apr 18 00:17:39 pmrpi01 kernel: [    2.722852] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Apr 18 00:17:39 pmrpi01 kernel: [    2.726972] usb 1-1.2: Product: RTL8191S WLAN Adapter 
Apr 18 00:17:39 pmrpi01 kernel: [    2.731039] usb 1-1.2: Manufacturer: Manufacturer Realtek 
Apr 18 00:17:39 pmrpi01 kernel: [    2.735123] usb 1-1.2: SerialNumber: 00e04c000001
Apr 18 00:17:39 pmrpi01 kernel: [    2.823579] EXT4-fs (mmcblk0p2): recovery complete
Apr 18 00:17:39 pmrpi01 kernel: [    2.833202] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Apr 18 00:17:39 pmrpi01 kernel: [    2.837621] VFS: Mounted root (ext4 filesystem) on device 179:2.
Apr 18 00:17:39 pmrpi01 kernel: [    2.841901] usb 1-1.3: new full-speed USB device number 5 using dwc_otg
Apr 18 00:17:39 pmrpi01 kernel: [    2.848712] devtmpfs: mounted
Apr 18 00:17:39 pmrpi01 kernel: [    2.853303] Freeing init memory: 128K
Apr 18 00:17:39 pmrpi01 kernel: [    2.960494] usb 1-1.3: New USB device found, idVendor=0403, idProduct=6001
Apr 18 00:17:39 pmrpi01 kernel: [    2.964757] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Apr 18 00:17:39 pmrpi01 kernel: [    2.969375] usb 1-1.3: Product: FT232R USB UART
Apr 18 00:17:39 pmrpi01 kernel: [    2.973584] usb 1-1.3: Manufacturer: FTDI
Apr 18 00:17:39 pmrpi01 kernel: [    2.978473] usb 1-1.3: SerialNumber: AL02FEOW
Apr 18 00:17:39 pmrpi01 kernel: [    5.176699] bcm2708_spi bcm2708_spi.0: master is unqueued, this is deprecated
Apr 18 00:17:39 pmrpi01 kernel: [    5.242472] bcm2708_spi bcm2708_spi.0: SPI Controller at 0x20204000 (irq 80)
Apr 18 00:17:39 pmrpi01 kernel: [    5.312480] bcm2708_i2c bcm2708_i2c.0: BSC0 Controller at 0x20205000 (irq 79) (baudrate 100k)
Apr 18 00:17:39 pmrpi01 kernel: [    5.335012] bcm2708_i2c bcm2708_i2c.1: BSC1 Controller at 0x20804000 (irq 79) (baudrate 100k)
Apr 18 00:17:39 pmrpi01 kernel: [    5.495370] Registered led device: led0
Apr 18 00:17:39 pmrpi01 kernel: [    5.617333] r8712u: module is from the staging directory, the quality is unknown, you have been warned.
Apr 18 00:17:39 pmrpi01 kernel: [    5.744848] r8712u: Staging version
Apr 18 00:17:39 pmrpi01 kernel: [    5.752268] usbcore: registered new interface driver usbserial
Apr 18 00:17:39 pmrpi01 kernel: [    5.756933] usbcore: registered new interface driver usbserial_generic
Apr 18 00:17:39 pmrpi01 kernel: [    5.940431] r8712u: register rtl8712_netdev_ops to netdev_ops
Apr 18 00:17:39 pmrpi01 kernel: [    5.944938] r8712u: USB_SPEED_LOW with 4 endpoints
Apr 18 00:17:39 pmrpi01 kernel: [    6.039648] USB Serial support registered for generic
Apr 18 00:17:39 pmrpi01 kernel: [    6.044164] usbserial: USB Serial Driver core
Apr 18 00:17:39 pmrpi01 kernel: [    6.184005] r8712u: Boot from EFUSE: Autoload OK
Apr 18 00:17:39 pmrpi01 kernel: [    6.332944] usbcore: registered new interface driver ftdi_sio
Apr 18 00:17:39 pmrpi01 kernel: [    6.466616] USB Serial support registered for FTDI USB Serial Device
Apr 18 00:17:39 pmrpi01 kernel: [    6.515918] ftdi_sio 1-1.3:1.0: FTDI USB Serial Device converter detected
Apr 18 00:17:39 pmrpi01 kernel: [    6.574768] usb 1-1.3: Detected FT232RL
Apr 18 00:17:39 pmrpi01 kernel: [    6.621647] usb 1-1.3: Number of endpoints 2
Apr 18 00:17:39 pmrpi01 kernel: [    6.626011] usb 1-1.3: Endpoint 1 MaxPacketSize 64
Apr 18 00:17:39 pmrpi01 kernel: [    6.710355] usb 1-1.3: Endpoint 2 MaxPacketSize 64
Apr 18 00:17:39 pmrpi01 kernel: [    6.760981] usb 1-1.3: Setting MaxPacketSize 64
Apr 18 00:17:39 pmrpi01 kernel: [    6.818664] usb 1-1.3: FTDI USB Serial Device converter now attached to ttyUSB0
Apr 18 00:17:39 pmrpi01 kernel: [    6.856562] ftdi_sio: v1.6.0:USB FTDI Serial Converters Driver
Apr 18 00:17:39 pmrpi01 kernel: [    9.295543] r8712u: CustomerID = 0x000a
Apr 18 00:17:39 pmrpi01 kernel: [    9.300328] r8712u: MAC Address from efuse = 24:05:0f:ac:79:1b
Apr 18 00:17:39 pmrpi01 kernel: [    9.304506] r8712u: Loading firmware from "rtlwifi/rtl8712u.bin"
Apr 18 00:17:39 pmrpi01 kernel: [    9.313989] usbcore: registered new interface driver r8712u
Apr 18 00:17:39 pmrpi01 kernel: [   13.892413] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Apr 18 00:17:39 pmrpi01 kernel: [   14.341656] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Apr 18 00:17:39 pmrpi01 kernel: [   15.121532] bcm2835 ALSA card created!
Apr 18 00:17:39 pmrpi01 kernel: [   15.130144] bcm2835 ALSA chip created!
Apr 18 00:17:39 pmrpi01 kernel: [   15.136992] bcm2835 ALSA chip created!
Apr 18 00:17:39 pmrpi01 kernel: [   15.147536] bcm2835 ALSA chip created!
Apr 18 00:17:39 pmrpi01 kernel: [   15.153879] bcm2835 ALSA chip created!
Apr 18 00:17:39 pmrpi01 kernel: [   15.162398] bcm2835 ALSA chip created!
Apr 18 00:17:39 pmrpi01 kernel: [   15.169089] bcm2835 ALSA chip created!
Apr 18 00:17:39 pmrpi01 kernel: [   15.177548] bcm2835 ALSA chip created!
Apr 18 00:17:39 pmrpi01 kernel: [   15.400905] i2c /dev entries driver
Apr 18 00:17:39 pmrpi01 kernel: [   19.615002] mmc0: missed completion of cmd 18 DMA (512/512 [1]/[1]) - ignoring it
Apr 18 00:17:39 pmrpi01 kernel: [   19.618430] mmc0: DMA IRQ 6 ignored - results were reset
Apr 18 00:17:39 pmrpi01 kernel: [   23.458810] r8712u: 1 RCR=0x153f00e
Apr 18 00:17:39 pmrpi01 kernel: [   23.461800] r8712u: 2 RCR=0x553f00e
Apr 18 00:17:39 pmrpi01 kernel: [   46.433615] bcm2835-cpufreq: switching to governor ondemand
Apr 18 00:17:40 pmrpi01 /usr/sbin/cron[1964]: (CRON) INFO (pidfile fd = 3)
Apr 18 00:17:40 pmrpi01 /usr/sbin/cron[1965]: (CRON) STARTUP (fork ok)
Apr 18 00:17:41 pmrpi01 /usr/sbin/cron[1965]: (CRON) INFO (Running @reboot jobs)
Apr 18 00:17:42 pmrpi01 kernel: [   46.433646] bcm2835-cpufreq: switching to governor ondemand<6>[   51.572394] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SS
Apr 18 00:17:43 pmrpi01 ntpd[2038]: ntpd 4.2.6p5@1.2349-o Fri May 18 20:30:57 UTC 2012 (1)
Apr 18 00:17:43 pmrpi01 ntpd[2039]: proto: precision = 1.000 usec
Apr 18 00:17:43 pmrpi01 ntpd[2039]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
Apr 18 00:17:43 pmrpi01 ntpd[2039]: Listen normally on 1 lo 127.0.0.1 UDP 123
Apr 18 00:17:43 pmrpi01 ntpd[2039]: Listen normally on 2 wlan0 192.168.188.46 UDP 123
Apr 18 00:17:43 pmrpi01 ntpd[2039]: peers refreshed
Apr 18 00:17:43 pmrpi01 ntpd[2039]: Listening on routing socket on fd #19 for interface updates
Apr 18 00:17:43 pmrpi01 ntpd[2039]: restrict: error in address '::' on line 41. Ignoring...
Apr 18 00:17:43 pmrpi01 ntpd[2039]: restrict: error in address '::1' on line 45. Ignoring...

kern.log:
siehe Anhang.

FHEM Logdatei:

Code Auswählen

2018.04.18 00:04:53 1: Heizung_ntf: -108.878
2018.04.18 00:16:26 1: /dev/ttyUSB0 disconnected, waiting to reappear (Heizung)
2018.04.18 00:16:26 3: VCONTROL: USB device /dev/ttyUSB0 disconnected, waiting to reappear
2018.04.18 00:16:31 3: VCONTROL: USB device /dev/ttyUSB0 reappeared
2018.04.18 00:16:31 3: VCONTROL: Initialization
2018.04.18 00:38:00 1: /dev/ttyUSB0 disconnected, waiting to reappear (Heizung)
2018.04.18 00:38:00 3: VCONTROL: USB device /dev/ttyUSB0 disconnected, waiting to reappear
2018.04.18 00:38:06 3: VCONTROL: USB device /dev/ttyUSB0 reappeared
2018.04.18 00:38:06 3: VCONTROL: Initialization
2018.04.18 00:17:44 2: MAX MaxScan: UtilsMaxScan_Initialize.71 MaxScan is starting
2018.04.18 00:17:44 1: Including fhem.cfg
2018.04.18 00:17:45 3: telnetPort: port 7072 opened
2018.04.18 00:17:47 3: WEB: port 8083 opened
2018.04.18 00:17:48 3: WEBphone: port 8084 opened
2018.04.18 00:17:48 3: WEBtablet: port 8085 opened
2018.04.18 00:17:50 3: VCONTROL: Define open DATEI 'v200kw1.cfg'
2018.04.18 00:17:50 3: VCONTROL: open DATEI 'v200kw1.cfg'

Ich habe das Netzteil ausgesteckt, kurz gewartet und neu gebootet: ging. WLAN Zugriff ging nicht.
CSL Stick durch Edimax Stick ersetzt: WLAN funktioniert wieder.

Den CSL WLAN Stick werde ich mal an meinem Testsystem probieren.

Was kann ich machen, dass ich das in Zukunft mitbekomme? Watchdog? Zweites System?

Danke + Gruß

PeMue

Wernieman · 21 Mai 2018, 09:45:30

Du hast einen Kompletten USB-Reset ... über alle Device. Da hilft nur, eines nach dem anderen abschalten und testen.

Was hast Du an dem USB2Ser-Adapter den drann? Könnte es "zurückstrahlen"?

PeMue · 21 Mai 2018, 17:28:04

Hallo Wernieman,

Zitat von: Wernieman am 21 Mai 2018, 09:45:30
Du hast einen Kompletten USB-Reset ... über alle Device.

was mich wundert ist, dass die Kiste danach nicht von sich aus bootet

Zitat von: Wernieman am 21 Mai 2018, 09:45:30
Da hilft nur, eines nach dem anderen abschalten und testen.

Ich habe schon den WLAN Stick getauscht, seitdem funktioniert die Sache.

Zitat von: Wernieman am 21 Mai 2018, 09:45:30
Was hast Du an dem USB2Ser-Adapter den drann? Könnte es "zurückstrahlen"?

Eher nicht (es ist eine eigene Leiterplatte

). Nein, Spaß beiseite: da ist nur eine Sende- bzw. Empfangsdiode drauf, sollte eigentlich funktionieren.

Danke + Gruß

Peter

Wernieman · 21 Mai 2018, 20:25:35

Zitatwas mich wundert ist, dass die Kiste danach nicht von sich aus bootet

Warum sollte er Pi booten, wenn das USB-Subsystem "nur" einen Reset macht?

PeMue · 21 Mai 2018, 21:50:42

Zitat von: Wernieman am 21 Mai 2018, 20:25:35
Warum sollte er Pi booten, wenn das USB-Subsystem "nur" einen Reset macht?

Dann die Gegenfrage: warum bleibt die Kiste dann stehen?

Gruß PeMue

Wernieman · 22 Mai 2018, 10:58:38

Beim Pi hängt leider vieles am USB-Bus ... zu viel. Da wundert es mich eigentlich eher, das nach Deinem Log es meistens weiterläuft ....