AcuRite 01036 quit updating. USB problem?

409 views
Skip to first unread message

Keith Meiser

unread,
May 18, 2015, 8:56:56 PM5/18/15
to weewx...@googlegroups.com
I'm running on Rasperian with acurite.py v0.17 but it started on v0.16 (AcuRite 01036). My DSL locked up and after I restarted it I noticed weewx was not updating. I rebooted the rasperry, halted and powered it back on, and unplugged and replugged the USB. This is what I am getting on restart.:
 
May 18 19:13:00 raspberrypi weewx[2922]: engine: Received signal TERM.
May 18 19:13:00 raspberrypi weewx[2922]: restx: Shut down Wunderground-PWS thread.
May 18 19:13:00 raspberrypi weewx[2922]: engine: Terminating weewx version 3.1.0
May 18 19:13:23 raspberrypi weewx[4755]: engine: Initializing weewx version 3.1.0
May 18 19:13:23 raspberrypi weewx[4755]: engine: Using Python 2.7.3 (default, Mar 18 2014, 05:13:23) #012[GCC 4.6.3]
May 18 19:13:23 raspberrypi weewx[4755]: engine: pid file is /var/run/weewx.pid
May 18 19:13:23 raspberrypi weewx[4757]: engine: Using configuration file /etc/weewx/weewx.conf
May 18 19:13:23 raspberrypi weewx[4757]: engine: Initializing engine
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading station type AcuRite (weewx.drivers.acurite)
May 18 19:13:23 raspberrypi weewx[4757]: acurite: driver version is 0.17
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.engine.StdTimeSynch
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.engine.StdTimeSynch
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.engine.StdConvert
May 18 19:13:23 raspberrypi weewx[4757]: engine: StdConvert target unit is 0x1
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.engine.StdConvert
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.engine.StdCalibrate
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.engine.StdCalibrate
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.engine.StdQC
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.engine.StdQC
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.wxservices.StdWXCalculate
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.wxservices.StdWXCalculate
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.engine.StdArchive
May 18 19:13:23 raspberrypi weewx[4757]: engine: Archive will use data binding wx_binding
May 18 19:13:23 raspberrypi weewx[4757]: engine: Record generation will be attempted in 'hardware'
May 18 19:13:23 raspberrypi weewx[4757]: engine: Using archive interval of 300 seconds
May 18 19:13:23 raspberrypi weewx[4757]: engine: Use LOOP data in hi/low calculations: 1
May 18 19:13:23 raspberrypi weewx[4757]: engine: Using binding 'wx_binding' to database '/var/lib/weewx/weewx.sdb'
May 18 19:13:23 raspberrypi weewx[4757]: engine: Starting backfill of daily summaries
May 18 19:13:23 raspberrypi weewx[4757]: engine: Daily summaries up to date.
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.engine.StdArchive
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.restx.StdStationRegistry
May 18 19:13:23 raspberrypi weewx[4757]: restx: StationRegistry: Data will not be posted. Missing option 'station_url'
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.restx.StdStationRegistry
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.restx.StdWunderground
May 18 19:13:23 raspberrypi weewx[4757]: restx: Wunderground-PWS: Data for station KILJOLIE17 will be posted
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.restx.StdWunderground
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.restx.StdPWSweather
May 18 19:13:23 raspberrypi weewx[4757]: restx: PWSWeather: Data will not be posted: Missing option 'station'
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.restx.StdPWSweather
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.restx.StdCWOP
May 18 19:13:23 raspberrypi weewx[4757]: restx: CWOP: Data will not be posted. Missing option: 'station'
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.restx.StdCWOP
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.restx.StdWOW
May 18 19:13:23 raspberrypi weewx[4757]: restx: WOW: Data will not be posted: Missing option 'station'
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.restx.StdWOW
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.restx.StdAWEKAS
May 18 19:13:23 raspberrypi weewx[4757]: restx: AWEKAS: Data will not be posted: Missing option 'username'
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.restx.StdAWEKAS
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.engine.StdPrint
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.engine.StdPrint
May 18 19:13:23 raspberrypi weewx[4757]: engine: Loading service weewx.engine.StdReport
May 18 19:13:23 raspberrypi weewx[4757]: engine: Finished loading service weewx.engine.StdReport
May 18 19:13:23 raspberrypi weewx[4757]: engine: Starting up weewx version 3.1.0
May 18 19:13:23 raspberrypi weewx[4757]: engine: Station does not support reading the time
May 18 19:13:23 raspberrypi weewx[4757]: engine: Starting main packet loop.
May 18 19:13:23 raspberrypi weewx[4757]: acurite: Found station at bus=001 device=006
May 18 19:13:23 raspberrypi weewx[4757]: acurite: Failed attempt 1 of 10 to get LOOP data: error sending control message: Broken pipe
May 18 19:13:53 raspberrypi weewx[4757]: acurite: Found station at bus=001 device=006
May 18 19:13:54 raspberrypi weewx[4757]: acurite: Failed attempt 2 of 10 to get LOOP data: error sending control message: Connection timed out
May 18 19:13:54 raspberrypi kernel: [92871.128155] usb 1-1.4: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 18 19:14:24 raspberrypi weewx[4757]: acurite: Found station at bus=001 device=006
May 18 19:14:25 raspberrypi weewx[4757]: acurite: Failed attempt 3 of 10 to get LOOP data: error sending control message: Broken pipe
May 18 19:14:55 raspberrypi weewx[4757]: acurite: Found station at bus=001 device=006
May 18 19:14:55 raspberrypi weewx[4757]: acurite: Failed attempt 4 of 10 to get LOOP data: error sending control message: Broken pipe
May 18 19:15:25 raspberrypi weewx[4757]: acurite: Found station at bus=001 device=006
May 18 19:15:26 raspberrypi weewx[4757]: acurite: Failed attempt 5 of 10 to get LOOP data: error sending control message: Connection timed out
May 18 19:15:26 raspberrypi kernel: [92962.918650] usb 1-1.4: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110

Keith Meiser

unread,
May 19, 2015, 6:23:12 PM5/19/15
to weewx...@googlegroups.com
I shutdown the Raspberry Pi and removed the monitor and keyboard. I relocated the AcuRite 01036 console into the bedroom and plugged the cable into a different USB port. I did not connect the monitor and keyboard. When I powered on the Raspberry, weewx was happy and communicating with the AcuRite again. OK for now.

-Keith

mwall

unread,
May 21, 2015, 12:52:40 PM5/21/15
to weewx...@googlegroups.com, kem...@gmail.com
On Monday, May 18, 2015 at 8:56:56 PM UTC-4, Keith Meiser wrote:

May 18 19:15:26 raspberrypi weewx[4757]: acurite: Failed attempt 5 of 10 to get LOOP data: error sending control message: Connection timed out
May 18 19:15:26 raspberrypi kernel: [92962.918650] usb 1-1.4: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110


keith,

if this happens again, please post all of the log entries.  the driver should retry 10 times. if all 10 retries fail, the weewx engine should attempt to reload the driver.  reloading the driver *should* reset the usb and re-establish communication.

m

Keith Meiser

unread,
May 21, 2015, 1:41:47 PM5/21/15
to weewx...@googlegroups.com, kem...@gmail.com
I had just finished cleaning out some erroneous entries in the sqlite db (including negative rainRate and rainRate of 27.75 and 145.6).
weewx was running fine before and I ran pi@raspberrypi ~ $ sudo wee_config_database weewx.conf --drop-daily after the changes.
Then I rebooted (sudo shutdown -r now). weewx failed to read the USB as soon as it came up.
Let me know if there are other logs you need. Here is the syslog from the reboot:

pi@raspberrypi ~ $ tail -600 /var/log/syslog
May 21 11:45:53 raspberrypi weewx[2600]: acurite: next read in 12 seconds
May 21 11:46:05 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:46:05 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:46:23 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:46:23 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:46:41 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:46:41 raspberrypi weewx[2600]: acurite: next read in 12 seconds
May 21 11:46:53 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:46:53 raspberrypi weewx[2600]: acurite: next read in 6 seconds
May 21 11:46:59 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:46:59 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:47:18 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:47:18 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:47:36 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:47:36 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:47:54 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:47:54 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:48:12 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:48:12 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:48:30 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:48:30 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:48:48 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:48:48 raspberrypi weewx[2600]: acurite: next read in 6 seconds
May 21 11:48:54 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:48:54 raspberrypi weewx[2600]: acurite: next read in 12 seconds
May 21 11:49:06 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:49:06 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:49:24 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:49:24 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:49:42 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:49:42 raspberrypi weewx[2600]: acurite: next read in 12 seconds
May 21 11:49:54 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:49:54 raspberrypi weewx[2600]: acurite: next read in 6 seconds
May 21 11:50:00 raspberrypi weewx[2600]: acurite: Found station at bus=001 device=004
May 21 11:50:00 raspberrypi weewx[2600]: acurite: next read in 18 seconds
May 21 11:50:13 raspberrypi weewx[2600]: engine: Received signal TERM.
May 21 11:50:13 raspberrypi weewx[2600]: engine: Shutting down StdReport thread
May 21 11:50:13 raspberrypi weewx[2600]: engine: StdReport thread has been terminated
May 21 11:50:13 raspberrypi weewx[2600]: restx: Shut down Wunderground-PWS thread.
May 21 11:50:13 raspberrypi weewx[2600]: engine: Terminating weewx version 3.1.0
May 21 12:08:55 raspberrypi shutdown[2819]: shutting down for system reboot
May 21 12:08:55 raspberrypi init: Switching to runlevel: 6
May 21 12:08:56 raspberrypi ifplugd(eth0)[1711]: Exiting.
May 21 12:08:56 raspberrypi ifplugd(wlan0)[1630]: Exiting.
May 21 12:08:58 raspberrypi ntpd[2246]: ntpd exiting on signal 15
May 21 12:08:58 raspberrypi ifplugd(lo)[1624]: Exiting.
May 21 12:09:04 raspberrypi kernel: imklog 5.8.11, log source = /proc/kmsg started.
May 21 12:09:04 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="5.8.11" x-pid="1955" x-info="http://www.rsyslog.com"] start
May 21 12:09:04 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0xf00
May 21 12:09:04 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpu
May 21 12:09:04 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpuacct
May 21 12:09:04 raspberrypi kernel: [    0.000000] Linux version 3.18.7-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.8.3 20140303 (prerelease) (crosstool-NG linaro-1.13.1+bzr2650 - Linaro GCC 2014.03) ) #755 SMP PREEMPT Thu Feb 12 17:20:48 GMT 2015
May 21 12:09:04 raspberrypi kernel: [    0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d
May 21 12:09:04 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
May 21 12:09:04 raspberrypi kernel: [    0.000000] Machine model: Raspberry Pi 2 Model B
May 21 12:09:04 raspberrypi kernel: [    0.000000] cma: Reserved 8 MiB at 0x3a800000
May 21 12:09:04 raspberrypi kernel: [    0.000000] Memory policy: Data cache writealloc
May 21 12:09:04 raspberrypi kernel: [    0.000000] On node 0 totalpages: 241664
May 21 12:09:04 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat 80820480, node_mem_map ba093000
May 21 12:09:04 raspberrypi kernel: [    0.000000]   Normal zone: 1888 pages used for memmap
May 21 12:09:04 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
May 21 12:09:04 raspberrypi kernel: [    0.000000]   Normal zone: 241664 pages, LIFO batch:31
May 21 12:09:04 raspberrypi kernel: [    0.000000] [bcm2709_smp_init_cpus] enter (8620->f3003010)
May 21 12:09:04 raspberrypi kernel: [    0.000000] [bcm2709_smp_init_cpus] ncores=4
May 21 12:09:04 raspberrypi kernel: [    0.000000] PERCPU: Embedded 11 pages/cpu @ba05d000 s12864 r8192 d24000 u45056
May 21 12:09:04 raspberrypi kernel: [    0.000000] pcpu-alloc: s12864 r8192 d24000 u45056 alloc=11*4096
May 21 12:09:04 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
May 21 12:09:04 raspberrypi kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 239776
May 21 12:09:04 raspberrypi kernel: [    0.000000] Kernel command line: dma.dmachans=0x7f35 bcm2708_fb.fbwidth=592 bcm2708_fb.fbheight=448 bcm2709.boardrev=0xa21041 bcm2709.serial=0xbd7f2e0e smsc95xx.macaddr=B8:27:EB:7F:2E:0E bcm2708_fb.fbswap=1 bcm2709.disk_led_gpio=47 bcm2709.disk_led_active_low=0 sdhci-bcm2708.emmc_clock_freq=250000000 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=ttyAMA0,115200 console=tty1 root=/dev/mmcblk0p6 rootfstype=ext4 elevator=deadline rootwait
May 21 12:09:04 raspberrypi kernel: [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
May 21 12:09:04 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
May 21 12:09:04 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
May 21 12:09:04 raspberrypi kernel: [    0.000000] Memory: 940740K/966656K available (5785K kernel code, 377K rwdata, 1760K rodata, 396K init, 771K bss, 25916K reserved)
May 21 12:09:04 raspberrypi kernel: [    0.000000] Virtual kernel memory layout:
May 21 12:09:04 raspberrypi kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
May 21 12:09:04 raspberrypi kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xffe00000   (2048 kB)
May 21 12:09:04 raspberrypi kernel: [    0.000000]     vmalloc : 0xbb800000 - 0xff000000   (1080 MB)
May 21 12:09:04 raspberrypi kernel: [    0.000000]     lowmem  : 0x80000000 - 0xbb000000   ( 944 MB)
May 21 12:09:04 raspberrypi kernel: [    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
May 21 12:09:04 raspberrypi kernel: [    0.000000]       .text : 0x80008000 - 0x80766748   (7546 kB)
May 21 12:09:04 raspberrypi kernel: [    0.000000]       .init : 0x80767000 - 0x807ca000   ( 396 kB)
May 21 12:09:04 raspberrypi kernel: [    0.000000]       .data : 0x807ca000 - 0x808287ec   ( 378 kB)
May 21 12:09:04 raspberrypi kernel: [    0.000000]        .bss : 0x808287ec - 0x808e96d4   ( 772 kB)
May 21 12:09:04 raspberrypi kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
May 21 12:09:04 raspberrypi kernel: [    0.000000] Preemptible hierarchical RCU implementation.
May 21 12:09:04 raspberrypi kernel: [    0.000000] NR_IRQS:480
May 21 12:09:04 raspberrypi kernel: [    0.000000] Architected cp15 timer(s) running at 19.20MHz (virt).
May 21 12:09:04 raspberrypi kernel: [    0.000015] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 3579139424256ns
May 21 12:09:04 raspberrypi kernel: [    0.000040] Switching to timer-based delay loop, resolution 52ns
May 21 12:09:04 raspberrypi kernel: [    0.000337] Console: colour dummy device 80x30
May 21 12:09:04 raspberrypi kernel: [    0.001764] console [tty1] enabled
May 21 12:09:04 raspberrypi kernel: [    0.001814] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
May 21 12:09:04 raspberrypi kernel: [    0.001898] pid_max: default: 32768 minimum: 301
May 21 12:09:04 raspberrypi kernel: [    0.002314] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
May 21 12:09:04 raspberrypi kernel: [    0.002373] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
May 21 12:09:04 raspberrypi kernel: [    0.003659] Initializing cgroup subsys memory
May 21 12:09:04 raspberrypi kernel: [    0.003749] Initializing cgroup subsys devices
May 21 12:09:04 raspberrypi kernel: [    0.003801] Initializing cgroup subsys freezer
May 21 12:09:04 raspberrypi kernel: [    0.003852] Initializing cgroup subsys net_cls
May 21 12:09:04 raspberrypi kernel: [    0.003916] Initializing cgroup subsys blkio
May 21 12:09:04 raspberrypi kernel: [    0.004036] CPU: Testing write buffer coherency: ok
May 21 12:09:04 raspberrypi kernel: [    0.004152] ftrace: allocating 19969 entries in 59 pages
May 21 12:09:04 raspberrypi kernel: [    0.053494] CPU0: update cpu_capacity 1024
May 21 12:09:04 raspberrypi kernel: [    0.053572] CPU0: thread -1, cpu 0, socket 15, mpidr 80000f00
May 21 12:09:04 raspberrypi kernel: [    0.053610] [bcm2709_smp_prepare_cpus] enter
May 21 12:09:04 raspberrypi kernel: [    0.053774] Setting up static identity map for 0x536c78 - 0x536cd0
May 21 12:09:04 raspberrypi kernel: [    0.113445] [bcm2709_boot_secondary] cpu:1 started (0) 18
May 21 12:09:04 raspberrypi kernel: [    0.113754] CPU1: Booted secondary processor
May 21 12:09:04 raspberrypi kernel: [    0.113762] [bcm2709_secondary_init] enter cpu:1
May 21 12:09:04 raspberrypi kernel: [    0.113815] CPU1: update cpu_capacity 1024
May 21 12:09:04 raspberrypi kernel: [    0.113824] CPU1: thread -1, cpu 1, socket 15, mpidr 80000f01
May 21 12:09:04 raspberrypi kernel: [    0.133407] [bcm2709_boot_secondary] cpu:2 started (0) 18
May 21 12:09:04 raspberrypi kernel: [    0.133662] CPU2: Booted secondary processor
May 21 12:09:04 raspberrypi kernel: [    0.133668] [bcm2709_secondary_init] enter cpu:2
May 21 12:09:04 raspberrypi kernel: [    0.133698] CPU2: update cpu_capacity 1024
May 21 12:09:04 raspberrypi kernel: [    0.133707] CPU2: thread -1, cpu 2, socket 15, mpidr 80000f02
May 21 12:09:04 raspberrypi kernel: [    0.153469] [bcm2709_boot_secondary] cpu:3 started (0) 18
May 21 12:09:04 raspberrypi kernel: [    0.153710] CPU3: Booted secondary processor
May 21 12:09:04 raspberrypi kernel: [    0.153717] [bcm2709_secondary_init] enter cpu:3
May 21 12:09:04 raspberrypi kernel: [    0.153750] CPU3: update cpu_capacity 1024
May 21 12:09:04 raspberrypi kernel: [    0.153758] CPU3: thread -1, cpu 3, socket 15, mpidr 80000f03
May 21 12:09:04 raspberrypi kernel: [    0.153856] Brought up 4 CPUs
May 21 12:09:04 raspberrypi kernel: [    0.153977] SMP: Total of 4 processors activated (153.60 BogoMIPS).
May 21 12:09:04 raspberrypi kernel: [    0.154010] CPU: All CPU(s) started in SVC mode.
May 21 12:09:04 raspberrypi kernel: [    0.155054] devtmpfs: initialized
May 21 12:09:04 raspberrypi kernel: [    0.178254] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5
May 21 12:09:04 raspberrypi kernel: [    0.180441] pinctrl core: initialized pinctrl subsystem
May 21 12:09:04 raspberrypi kernel: [    0.183499] NET: Registered protocol family 16
May 21 12:09:04 raspberrypi kernel: [    0.189116] DMA: preallocated 4096 KiB pool for atomic coherent allocations
May 21 12:09:04 raspberrypi kernel: [    0.213268] cpuidle: using governor ladder
May 21 12:09:04 raspberrypi kernel: [    0.243299] cpuidle: using governor menu
May 21 12:09:04 raspberrypi kernel: [    0.243697] bcm2709.uart_clock = 3000000
May 21 12:09:04 raspberrypi kernel: [    0.246451] No ATAGs?
May 21 12:09:04 raspberrypi kernel: [    0.246516] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
May 21 12:09:04 raspberrypi kernel: [    0.246571] hw-breakpoint: maximum watchpoint size is 8 bytes.
May 21 12:09:04 raspberrypi kernel: [    0.246633] mailbox: Broadcom VideoCore Mailbox driver
May 21 12:09:04 raspberrypi kernel: [    0.246764] bcm2708_vcio: mailbox at f300b880
May 21 12:09:04 raspberrypi kernel: [    0.247149] bcm_power: Broadcom power driver
May 21 12:09:04 raspberrypi kernel: [    0.247187] bcm_power_open() -> 0
May 21 12:09:04 raspberrypi kernel: [    0.247212] bcm_power_request(0, 8)
May 21 12:09:04 raspberrypi kernel: [    0.747913] bcm_mailbox_read -> 00000080, 0
May 21 12:09:04 raspberrypi kernel: [    0.747945] bcm_power_request -> 0
May 21 12:09:04 raspberrypi kernel: [    0.748100] Serial: AMBA PL011 UART driver
May 21 12:09:04 raspberrypi kernel: [    0.748261] dev:f1: ttyAMA0 at MMIO 0x3f201000 (irq = 83, base_baud = 0) is a PL011 rev3
May 21 12:09:04 raspberrypi kernel: [    1.266574] console [ttyAMA0] enabled
May 21 12:09:04 raspberrypi kernel: [    1.336683] SCSI subsystem initialized
May 21 12:09:04 raspberrypi kernel: [    1.340703] usbcore: registered new interface driver usbfs
May 21 12:09:04 raspberrypi kernel: [    1.346367] usbcore: registered new interface driver hub
May 21 12:09:04 raspberrypi kernel: [    1.351826] usbcore: registered new device driver usb
May 21 12:09:04 raspberrypi kernel: [    1.358790] Switched to clocksource arch_sys_counter
May 21 12:09:04 raspberrypi kernel: [    1.393656] FS-Cache: Loaded
May 21 12:09:04 raspberrypi kernel: [    1.396909] CacheFiles: Loaded
May 21 12:09:04 raspberrypi kernel: [    1.412341] NET: Registered protocol family 2
May 21 12:09:04 raspberrypi kernel: [    1.417971] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
May 21 12:09:04 raspberrypi kernel: [    1.425226] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
May 21 12:09:04 raspberrypi kernel: [    1.431924] TCP: Hash tables configured (established 8192 bind 8192)
May 21 12:09:04 raspberrypi kernel: [    1.438395] TCP: reno registered
May 21 12:09:04 raspberrypi kernel: [    1.441675] UDP hash table entries: 512 (order: 2, 16384 bytes)
May 21 12:09:04 raspberrypi kernel: [    1.447660] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
May 21 12:09:04 raspberrypi kernel: [    1.454413] NET: Registered protocol family 1
May 21 12:09:04 raspberrypi kernel: [    1.459258] RPC: Registered named UNIX socket transport module.
May 21 12:09:04 raspberrypi kernel: [    1.465200] RPC: Registered udp transport module.
May 21 12:09:04 raspberrypi kernel: [    1.469981] RPC: Registered tcp transport module.
May 21 12:09:04 raspberrypi kernel: [    1.474700] RPC: Registered tcp NFSv4.1 backchannel transport module.
May 21 12:09:04 raspberrypi kernel: [    1.482197] bcm2708_dma: DMA manager at f3007000
May 21 12:09:04 raspberrypi kernel: [    1.487004] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB)
May 21 12:09:04 raspberrypi kernel: [    1.496989] futex hash table entries: 1024 (order: 4, 65536 bytes)
May 21 12:09:04 raspberrypi kernel: [    1.503504] audit: initializing netlink subsys (disabled)
May 21 12:09:04 raspberrypi kernel: [    1.509058] audit: type=2000 audit(1.299:1): initialized
May 21 12:09:04 raspberrypi kernel: [    1.530800] VFS: Disk quotas dquot_6.5.2
May 21 12:09:04 raspberrypi kernel: [    1.535119] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
May 21 12:09:04 raspberrypi kernel: [    1.544851] FS-Cache: Netfs 'nfs' registered for caching
May 21 12:09:04 raspberrypi kernel: [    1.551214] NFS: Registering the id_resolver key type
May 21 12:09:04 raspberrypi kernel: [    1.556357] Key type id_resolver registered
May 21 12:09:04 raspberrypi kernel: [    1.560617] Key type id_legacy registered
May 21 12:09:04 raspberrypi kernel: [    1.565699] msgmni has been set to 1853
May 21 12:09:04 raspberrypi kernel: [    1.571317] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
May 21 12:09:04 raspberrypi kernel: [    1.579040] io scheduler noop registered
May 21 12:09:04 raspberrypi kernel: [    1.582994] io scheduler deadline registered (default)
May 21 12:09:04 raspberrypi kernel: [    1.588457] io scheduler cfq registered
May 21 12:09:04 raspberrypi kernel: [    1.595013] BCM2708FB: allocated DMA memory fac00000
May 21 12:09:04 raspberrypi kernel: [    1.600149] BCM2708FB: allocated DMA channel 0 @ f3007000
May 21 12:09:04 raspberrypi kernel: [    1.611920] Console: switching to colour frame buffer device 74x28
May 21 12:09:04 raspberrypi kernel: [    1.624304] bcm2708-dmaengine bcm2708-dmaengine: Load BCM2835 DMA engine driver
May 21 12:09:04 raspberrypi kernel: [    1.635475] uart-pl011 dev:f1: no DMA platform data
May 21 12:09:04 raspberrypi kernel: [    1.642706] vc-cma: Videocore CMA driver
May 21 12:09:04 raspberrypi kernel: [    1.648348] vc-cma: vc_cma_base      = 0x00000000
May 21 12:09:04 raspberrypi kernel: [    1.654752] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
May 21 12:09:04 raspberrypi kernel: [    1.661798] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
May 21 12:09:04 raspberrypi kernel: [    1.680617] brd: module loaded
May 21 12:09:04 raspberrypi kernel: [    1.691347] loop: module loaded
May 21 12:09:04 raspberrypi kernel: [    1.696376] vchiq: vchiq_init_state: slot_zero = 0xba800000, is_master = 0
May 21 12:09:04 raspberrypi kernel: [    1.707134] Loading iSCSI transport class v2.0-870.
May 21 12:09:04 raspberrypi kernel: [    1.714642] usbcore: registered new interface driver smsc95xx
May 21 12:09:04 raspberrypi kernel: [    1.722115] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
May 21 12:09:04 raspberrypi kernel: [    1.929877] Core Release: 2.80a
May 21 12:09:04 raspberrypi kernel: [    1.934629] Setting default values for core params
May 21 12:09:04 raspberrypi kernel: [    1.941067] Finished setting default values for core params
May 21 12:09:04 raspberrypi kernel: [    2.148653] Using Buffer DMA mode
May 21 12:09:04 raspberrypi kernel: [    2.153594] Periodic Transfer Interrupt Enhancement - disabled
May 21 12:09:04 raspberrypi kernel: [    2.161110] Multiprocessor Interrupt Enhancement - disabled
May 21 12:09:04 raspberrypi kernel: [    2.168354] OTG VER PARAM: 0, OTG VER FLAG: 0
May 21 12:09:04 raspberrypi kernel: [    2.174418] Dedicated Tx FIFOs mode
May 21 12:09:04 raspberrypi kernel: [    2.179904] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xbac14000 dma = 0xfac14000 len=9024
May 21 12:09:04 raspberrypi kernel: [    2.193115] FIQ FSM acceleration enabled for :
May 21 12:09:04 raspberrypi kernel: [    2.193115] Non-periodic Split Transactions
May 21 12:09:04 raspberrypi kernel: [    2.193115] Periodic Split Transactions
May 21 12:09:04 raspberrypi kernel: [    2.193115] High-Speed Isochronous Endpoints
May 21 12:09:04 raspberrypi kernel: [    2.216350] dwc_otg: Microframe scheduler enabled
May 21 12:09:04 raspberrypi kernel: [    2.216421] WARN::hcd_init_fiq:412: FIQ on core 1 at 0x803e4200
May 21 12:09:04 raspberrypi kernel: [    2.224081] WARN::hcd_init_fiq:413: FIQ ASM at 0x803e455c length 36
May 21 12:09:04 raspberrypi kernel: [    2.232070] WARN::hcd_init_fiq:438: MPHI regs_base at 0xbb806000
May 21 12:09:04 raspberrypi kernel: [    2.239850] dwc_otg bcm2708_usb: DWC OTG Controller
May 21 12:09:04 raspberrypi kernel: [    2.246474] dwc_otg bcm2708_usb: new USB bus registered, assigned bus number 1
May 21 12:09:04 raspberrypi kernel: [    2.257075] dwc_otg bcm2708_usb: irq 32, io mem 0x00000000
May 21 12:09:04 raspberrypi kernel: [    2.264343] Init: Port Power? op_state=1
May 21 12:09:04 raspberrypi kernel: [    2.269975] Init: Power Port (0)
May 21 12:09:04 raspberrypi kernel: [    2.275143] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
May 21 12:09:04 raspberrypi kernel: [    2.285313] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
May 21 12:09:04 raspberrypi kernel: [    2.296003] usb usb1: Product: DWC OTG Controller
May 21 12:09:04 raspberrypi kernel: [    2.302487] usb usb1: Manufacturer: Linux 3.18.7-v7+ dwc_otg_hcd
May 21 12:09:04 raspberrypi kernel: [    2.310289] usb usb1: SerialNumber: bcm2708_usb
May 21 12:09:04 raspberrypi kernel: [    2.317566] hub 1-0:1.0: USB hub found
May 21 12:09:04 raspberrypi kernel: [    2.323177] hub 1-0:1.0: 1 port detected
May 21 12:09:04 raspberrypi kernel: [    2.329314] dwc_otg: FIQ enabled
May 21 12:09:04 raspberrypi kernel: [    2.329328] dwc_otg: NAK holdoff enabled
May 21 12:09:04 raspberrypi kernel: [    2.329339] dwc_otg: FIQ split-transaction FSM enabled
May 21 12:09:04 raspberrypi kernel: [    2.329378] Module dwc_common_port init
May 21 12:09:04 raspberrypi kernel: [    2.329757] usbcore: registered new interface driver usb-storage
May 21 12:09:04 raspberrypi kernel: [    2.337786] mousedev: PS/2 mouse device common for all mice
May 21 12:09:04 raspberrypi kernel: [    2.345848] bcm2835-cpufreq: min=600000 max=900000
May 21 12:09:04 raspberrypi kernel: [    2.352779] sdhci: Secure Digital Host Controller Interface driver
May 21 12:09:04 raspberrypi kernel: [    2.360815] sdhci: Copyright(c) Pierre Ossman
May 21 12:09:04 raspberrypi kernel: [    2.367118] DMA channels allocated for the MMC driver
May 21 12:09:04 raspberrypi kernel: [    2.408826] Load BCM2835 MMC driver
May 21 12:09:04 raspberrypi kernel: [    2.416083] sdhci-pltfm: SDHCI platform and OF driver helper
May 21 12:09:04 raspberrypi kernel: [    2.425315] ledtrig-cpu: registered to indicate activity on CPUs
May 21 12:09:04 raspberrypi kernel: [    2.437705] hidraw: raw HID events driver (C) Jiri Kosina
May 21 12:09:04 raspberrypi kernel: [    2.445245] usbcore: registered new interface driver usbhid
May 21 12:09:04 raspberrypi kernel: [    2.453706] usbhid: USB HID core driver
May 21 12:09:04 raspberrypi kernel: [    2.460775] TCP: cubic registered
May 21 12:09:04 raspberrypi kernel: [    2.467939] Initializing XFRM netlink socket
May 21 12:09:04 raspberrypi kernel: [    2.474072] NET: Registered protocol family 17
May 21 12:09:04 raspberrypi kernel: [    2.483750] Key type dns_resolver registered
May 21 12:09:04 raspberrypi kernel: [    2.490251] Registering SWP/SWPB emulation handler
May 21 12:09:04 raspberrypi kernel: [    2.497620] registered taskstats version 1
May 21 12:09:04 raspberrypi kernel: [    2.503628] vc-sm: Videocore shared memory driver
May 21 12:09:04 raspberrypi kernel: [    2.509955] [vc_sm_connected_init]: start
May 21 12:09:04 raspberrypi kernel: [    2.516012] mmc0: host does not support reading read-only switch, assuming write-enable
May 21 12:09:04 raspberrypi kernel: [    2.527725] [vc_sm_connected_init]: end - returning 0
May 21 12:09:04 raspberrypi kernel: [    2.528945] Indeed it is in host mode hprt0 = 00021501
May 21 12:09:04 raspberrypi kernel: [    2.529347] mmc0: new high speed SDHC card at address 0001
May 21 12:09:04 raspberrypi kernel: [    2.529964] mmcblk0: mmc0:0001 00000 29.8 GiB
May 21 12:09:04 raspberrypi kernel: [    2.532468]  mmcblk0: p1 p2 < p5 p6 > p3
May 21 12:09:04 raspberrypi kernel: [    2.582975] EXT4-fs (mmcblk0p6): mounted filesystem with ordered data mode. Opts: (null)
May 21 12:09:04 raspberrypi kernel: [    2.594493] VFS: Mounted root (ext4 filesystem) readonly on device 179:6.
May 21 12:09:04 raspberrypi kernel: [    2.610972] devtmpfs: mounted
May 21 12:09:04 raspberrypi kernel: [    2.616287] Freeing unused kernel memory: 396K (80767000 - 807ca000)
May 21 12:09:04 raspberrypi kernel: [    2.708912] usb 1-1: new high-speed USB device number 2 using dwc_otg
May 21 12:09:04 raspberrypi kernel: [    2.717364] Indeed it is in host mode hprt0 = 00001101
May 21 12:09:04 raspberrypi kernel: [    2.919244] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
May 21 12:09:04 raspberrypi kernel: [    2.929698] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
May 21 12:09:04 raspberrypi kernel: [    2.941740] hub 1-1:1.0: USB hub found
May 21 12:09:04 raspberrypi kernel: [    2.947606] hub 1-1:1.0: 5 ports detected
May 21 12:09:04 raspberrypi kernel: [    3.229110] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
May 21 12:09:04 raspberrypi kernel: [    3.359377] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
May 21 12:09:04 raspberrypi kernel: [    3.370672] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
May 21 12:09:04 raspberrypi kernel: [    3.390740] smsc95xx v1.0.4
May 21 12:09:04 raspberrypi kernel: [    3.453208] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:7f:2e:0e
May 21 12:09:04 raspberrypi kernel: [    3.549073] usb 1-1.3: new low-speed USB device number 4 using dwc_otg
May 21 12:09:04 raspberrypi kernel: [    3.666762] usb 1-1.3: New USB device found, idVendor=24c0, idProduct=0003
May 21 12:09:04 raspberrypi kernel: [    3.677968] usb 1-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0
May 21 12:09:04 raspberrypi kernel: [    3.689970] usb 1-1.3: Product: Chaney Instrument
May 21 12:09:04 raspberrypi kernel: [    4.392416] random: nonblocking pool is initialized
May 21 12:09:04 raspberrypi kernel: [   13.708899] hid-generic 0003:24C0:0003.0001: usb_submit_urb(ctrl) failed: -1
May 21 12:09:04 raspberrypi kernel: [   13.720345] hid-generic 0003:24C0:0003.0001: timeout initializing reports
May 21 12:09:04 raspberrypi kernel: [   13.732403] input: Chaney Instrument as /devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:24C0:0003.0001/input/input0
May 21 12:09:04 raspberrypi kernel: [   13.749008] hid-generic 0003:24C0:0003.0001: input,hidraw0: USB HID v1.11 Device [Chaney Instrument] on usb-bcm2708_usb-1.3/input0
May 21 12:09:04 raspberrypi kernel: [   13.868944] usb 1-1.5: new high-speed USB device number 5 using dwc_otg
May 21 12:09:04 raspberrypi kernel: [   13.996343] usb 1-1.5: New USB device found, idVendor=148f, idProduct=5370
May 21 12:09:04 raspberrypi kernel: [   14.007726] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
May 21 12:09:04 raspberrypi kernel: [   14.019545] usb 1-1.5: Product: 802.11 n WLAN
May 21 12:09:04 raspberrypi kernel: [   14.026132] usb 1-1.5: Manufacturer: Ralink
May 21 12:09:04 raspberrypi kernel: [   14.032504] usb 1-1.5: SerialNumber: 1.0
May 21 12:09:04 raspberrypi kernel: [   14.181047] cfg80211: Calling CRDA to update world regulatory domain
May 21 12:09:04 raspberrypi kernel: [   14.368935] usb 1-1.5: reset high-speed USB device number 5 using dwc_otg
May 21 12:09:04 raspberrypi kernel: [   14.489607] ieee80211 phy0: rt2x00_set_rt: Info - RT chipset 5390, rev 0502 detected
May 21 12:09:04 raspberrypi kernel: [   14.522211] ieee80211 phy0: rt2x00_set_rf: Info - RF chipset 5370 detected
May 21 12:09:04 raspberrypi kernel: [   14.554954] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
May 21 12:09:04 raspberrypi kernel: [   14.556990] usbcore: registered new interface driver rt2800usb
May 21 12:09:04 raspberrypi kernel: [   15.968397] EXT4-fs (mmcblk0p6): re-mounted. Opts: (null)
May 21 12:09:04 raspberrypi kernel: [   16.254882] EXT4-fs (mmcblk0p6): re-mounted. Opts: (null)
May 21 12:09:04 raspberrypi kernel: [   20.751847] ieee80211 phy0: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin'
May 21 12:09:04 raspberrypi kernel: [   20.754365] ieee80211 phy0: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.29
May 21 12:09:04 raspberrypi kernel: [   21.313865] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
May 21 12:09:04 raspberrypi wpa_supplicant[1750]: wlan0: SME: Trying to authenticate with 20:aa:4b:cd:5d:f4 (SSID='Mockingjay' freq=2462 MHz)
May 21 12:09:04 raspberrypi kernel: [   22.903170] wlan0: authenticate with 20:aa:4b:cd:5d:f4
May 21 12:09:04 raspberrypi wpa_supplicant[1750]: wlan0: Trying to associate with 20:aa:4b:cd:5d:f4 (SSID='Mockingjay' freq=2462 MHz)
May 21 12:09:04 raspberrypi kernel: [   22.968279] wlan0: send auth to 20:aa:4b:cd:5d:f4 (try 1/3)
May 21 12:09:04 raspberrypi kernel: [   22.976991] wlan0: authenticated
May 21 12:09:04 raspberrypi kernel: [   22.977795] rt2800usb 1-1.5:1.0 wlan0: disabling HT/VHT due to WEP/TKIP use
May 21 12:09:04 raspberrypi kernel: [   22.978912] wlan0: associate with 20:aa:4b:cd:5d:f4 (try 1/3)
May 21 12:09:04 raspberrypi kernel: [   22.981757] wlan0: RX AssocResp from 20:aa:4b:cd:5d:f4 (capab=0x411 status=0 aid=2)
May 21 12:09:04 raspberrypi wpa_supplicant[1750]: wlan0: Associated with 20:aa:4b:cd:5d:f4
May 21 12:09:04 raspberrypi kernel: [   22.991170] wlan0: associated
May 21 12:09:04 raspberrypi wpa_supplicant[1750]: wlan0: WPA: Key negotiation completed with 20:aa:4b:cd:5d:f4 [PTK=TKIP GTK=TKIP]
May 21 12:09:04 raspberrypi wpa_supplicant[1750]: wlan0: CTRL-EVENT-CONNECTED - Connection to 20:aa:4b:cd:5d:f4 completed (auth) [id=0 id_str=]
May 21 12:09:04 raspberrypi wpa_action: WPA_IFACE=wlan0 WPA_ACTION=CONNECTED
May 21 12:09:04 raspberrypi wpa_action: WPA_ID=0 WPA_ID_STR= WPA_CTRL_DIR=/var/run/wpa_supplicant
May 21 12:09:04 raspberrypi wpa_action: ifup wlan0=default
May 21 12:09:04 raspberrypi ifplugd(wlan0)[1646]: Link beat detected.
May 21 12:09:04 raspberrypi dhclient: Internet Systems Consortium DHCP Client 4.2.2
May 21 12:09:04 raspberrypi dhclient: Copyright 2004-2011 Internet Systems Consortium.
May 21 12:09:04 raspberrypi dhclient: All rights reserved.
May 21 12:09:04 raspberrypi dhclient: For info, please visit https://www.isc.org/software/dhcp/
May 21 12:09:04 raspberrypi dhclient:
May 21 12:09:04 raspberrypi ifplugd(wlan0)[1646]: Executing '/etc/ifplugd/ifplugd.action wlan0 up'.
May 21 12:09:04 raspberrypi ifplugd(wlan0)[1646]: client: /sbin/ifup: interface wlan0 already configured
May 21 12:09:04 raspberrypi ifplugd(wlan0)[1646]: Program executed successfully.
May 21 12:09:04 raspberrypi dhclient: Listening on LPF/wlan0/00:c1:40:48:11:2f
May 21 12:09:04 raspberrypi dhclient: Sending on   LPF/wlan0/00:c1:40:48:11:2f
May 21 12:09:04 raspberrypi dhclient: Sending on   Socket/fallback
May 21 12:09:04 raspberrypi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 5
May 21 12:09:05 raspberrypi /usr/sbin/cron[2245]: (CRON) INFO (pidfile fd = 3)
May 21 12:09:05 raspberrypi ntpd[2232]: ntpd 4.2...@1.2349-o Mon Feb  9 03:34:42 UTC 2015 (1)
May 21 12:09:05 raspberrypi /usr/sbin/cron[2258]: (CRON) STARTUP (fork ok)
May 21 12:09:05 raspberrypi /usr/sbin/cron[2258]: (CRON) INFO (Running @reboot jobs)
May 21 12:09:05 raspberrypi ntpd[2262]: proto: precision = 1.250 usec
May 21 12:09:05 raspberrypi ntpd[2262]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
May 21 12:09:05 raspberrypi ntpd[2262]: Listen normally on 1 lo 127.0.0.1 UDP 123
May 21 12:09:05 raspberrypi ntpd[2262]: peers refreshed
May 21 12:09:05 raspberrypi ntpd[2262]: Listening on routing socket on fd #18 for interface updates
May 21 12:09:05 raspberrypi ntpd[2262]: restrict: error in address '::' on line 38. Ignoring...
May 21 12:09:05 raspberrypi ntpd[2262]: restrict: error in address '::1' on line 42. Ignoring...
May 21 12:09:05 raspberrypi ntpd[2262]: Deferring DNS for 0.debian.pool.ntp.org 1
May 21 12:09:05 raspberrypi ntpd[2262]: Deferring DNS for 1.debian.pool.ntp.org 1
May 21 12:09:05 raspberrypi ntpd[2262]: Deferring DNS for 2.debian.pool.ntp.org 1
May 21 12:09:05 raspberrypi ntpd[2262]: Deferring DNS for 3.debian.pool.ntp.org 1
May 21 12:09:05 raspberrypi ntpd[2287]: signal_no_reset: signal 17 had flags 4000000
May 21 12:09:05 raspberrypi kernel: [   24.067028] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SSFS
May 21 12:09:05 raspberrypi dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
May 21 12:09:05 raspberrypi dhclient: DHCPOFFER from 192.168.0.2
May 21 12:09:05 raspberrypi dhclient: DHCPACK from 192.168.0.2
May 21 12:09:06 raspberrypi dhclient: bound to 192.168.0.122 -- renewal in 41042 seconds.
May 21 12:09:06 raspberrypi weewx[2251]: engine: Initializing weewx version 3.1.0
May 21 12:09:06 raspberrypi weewx[2251]: engine: Using Python 2.7.3 (default, Mar 18 2014, 05:13:23) #012[GCC 4.6.3]
May 21 12:09:06 raspberrypi weewx[2251]: engine: pid file is /var/run/weewx.pid
May 21 12:09:06 raspberrypi weewx[2586]: engine: Using configuration file /etc/weewx/weewx.conf
May 21 12:09:06 raspberrypi weewx[2586]: engine: Initializing engine
May 21 12:09:06 raspberrypi weewx[2586]: engine: Loading station type AcuRite (weewx.drivers.acurite)
May 21 12:09:06 raspberrypi weewx[2586]: acurite: driver version is 0.17
May 21 12:09:06 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdTimeSynch
May 21 12:09:06 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdTimeSynch
May 21 12:09:06 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdConvert
May 21 12:09:06 raspberrypi weewx[2586]: engine: StdConvert target unit is 0x1
May 21 12:09:06 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdConvert
May 21 12:09:06 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdCalibrate
May 21 12:09:06 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdCalibrate
May 21 12:09:06 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdQC
May 21 12:09:06 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdQC
May 21 12:09:06 raspberrypi weewx[2586]: engine: Loading service weewx.wxservices.StdWXCalculate
May 21 12:09:06 raspberrypi weewx[2586]: engine: Finished loading service weewx.wxservices.StdWXCalculate
May 21 12:09:06 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdArchive
May 21 12:09:06 raspberrypi weewx[2586]: engine: Archive will use data binding wx_binding
May 21 12:09:06 raspberrypi weewx[2586]: engine: Record generation will be attempted in 'hardware'
May 21 12:09:06 raspberrypi weewx[2586]: engine: Using archive interval of 300 seconds
May 21 12:09:06 raspberrypi weewx[2586]: engine: Use LOOP data in hi/low calculations: 1
May 21 12:09:06 raspberrypi wpa_action: creating sendsigs omission pidfile: /run/sendsigs.omit.d/wpasupplicant.wpa_supplicant.wlan0.pid
May 21 12:09:06 raspberrypi weewx[2586]: manager: Created daily summary tables
May 21 12:09:06 raspberrypi weewx[2586]: engine: Using binding 'wx_binding' to database '/var/lib/weewx/weewx.sdb'
May 21 12:09:06 raspberrypi weewx[2586]: engine: Starting backfill of daily summaries
May 21 12:09:06 raspberrypi wpa_action: bssid=20:aa:4b:cd:5d:f4
May 21 12:09:06 raspberrypi wpa_action: ssid=Mockingjay
May 21 12:09:06 raspberrypi wpa_action: id=0
May 21 12:09:06 raspberrypi wpa_action: mode=station
May 21 12:09:06 raspberrypi wpa_action: pairwise_cipher=TKIP
May 21 12:09:06 raspberrypi wpa_action: group_cipher=TKIP
May 21 12:09:06 raspberrypi wpa_action: key_mgmt=WPA-PSK
May 21 12:09:06 raspberrypi wpa_action: wpa_state=COMPLETED
May 21 12:09:06 raspberrypi wpa_action: ip_address=192.168.0.122
May 21 12:09:06 raspberrypi wpa_action: address=00:c1:40:48:11:2f
May 21 12:09:07 raspberrypi ntpd[2262]: Listen normally on 2 wlan0 192.168.0.122 UDP 123
May 21 12:09:07 raspberrypi ntpd[2262]: peers refreshed
May 21 12:09:07 raspberrypi ntpd_intres[2287]: DNS 0.debian.pool.ntp.org -> 173.44.32.10
May 21 12:09:15 raspberrypi ntpd_intres[2287]: DNS 1.debian.pool.ntp.org -> 50.116.55.65
May 21 12:09:15 raspberrypi ntpd_intres[2287]: DNS 2.debian.pool.ntp.org -> 193.95.228.232
May 21 12:09:15 raspberrypi ntpd_intres[2287]: DNS 3.debian.pool.ntp.org -> 198.55.111.5
May 21 12:10:22 raspberrypi weewx[2586]: engine: Processed 8757 records to backfill 32 day summaries in 76.01 seconds
May 21 12:10:22 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdArchive
May 21 12:10:22 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdStationRegistry
May 21 12:10:22 raspberrypi weewx[2586]: restx: StationRegistry: Data will not be posted. Missing option 'station_url'
May 21 12:10:22 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdStationRegistry
May 21 12:10:22 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdWunderground
May 21 12:10:22 raspberrypi weewx[2586]: restx: Wunderground-PWS: Data for station KILJOLIE17 will be posted
May 21 12:10:22 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdWunderground
May 21 12:10:22 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdPWSweather
May 21 12:10:22 raspberrypi weewx[2586]: restx: PWSWeather: Data will not be posted: Missing option 'station'
May 21 12:10:22 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdPWSweather
May 21 12:10:22 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdCWOP
May 21 12:10:22 raspberrypi weewx[2586]: restx: CWOP: Data will not be posted. Missing option: 'station'
May 21 12:10:22 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdCWOP
May 21 12:10:22 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdWOW
May 21 12:10:22 raspberrypi weewx[2586]: restx: WOW: Data will not be posted: Missing option 'station'
May 21 12:10:22 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdWOW
May 21 12:10:22 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdAWEKAS
May 21 12:10:22 raspberrypi weewx[2586]: restx: AWEKAS: Data will not be posted: Missing option 'username'
May 21 12:10:22 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdAWEKAS
May 21 12:10:22 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdPrint
May 21 12:10:22 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdPrint
May 21 12:10:22 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdReport
May 21 12:10:22 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdReport
May 21 12:10:22 raspberrypi weewx[2586]: engine: Starting up weewx version 3.1.0
May 21 12:10:22 raspberrypi weewx[2586]: engine: Station does not support reading the time
May 21 12:10:22 raspberrypi weewx[2586]: engine: Starting main packet loop.
May 21 12:10:22 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:10:23 raspberrypi weewx[2586]: acurite: Set configuration failed: could not set config 1: Broken pipe
May 21 12:10:24 raspberrypi weewx[2586]: acurite: Failed attempt 1 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:10:24 raspberrypi kernel: [   61.819072] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:10:54 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:10:55 raspberrypi weewx[2586]: acurite: Failed attempt 2 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:11:25 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:11:26 raspberrypi weewx[2586]: acurite: Failed attempt 3 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:11:26 raspberrypi kernel: [  123.569418] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:11:56 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:11:57 raspberrypi weewx[2586]: acurite: Failed attempt 4 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:11:57 raspberrypi kernel: [  154.599529] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:12:27 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:12:27 raspberrypi weewx[2586]: acurite: Failed attempt 5 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:12:57 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:12:57 raspberrypi weewx[2586]: acurite: Failed attempt 6 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:13:27 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:13:27 raspberrypi weewx[2586]: acurite: Failed attempt 7 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:13:57 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:13:57 raspberrypi weewx[2586]: acurite: Set configuration failed: could not set config 1: Broken pipe
May 21 12:13:57 raspberrypi weewx[2586]: acurite: Failed attempt 8 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:14:28 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:14:29 raspberrypi weewx[2586]: acurite: Failed attempt 9 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:14:29 raspberrypi kernel: [  306.460293] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:14:59 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:15:00 raspberrypi weewx[2586]: acurite: Failed attempt 10 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:15:00 raspberrypi kernel: [  338.180469] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:15:30 raspberrypi weewx[2586]: acurite: Max retries (10) exceeded for LOOP data
May 21 12:15:30 raspberrypi weewx[2586]: restx: Shut down Wunderground-PWS thread.
May 21 12:15:30 raspberrypi weewx[2586]: engine: Caught WeeWxIOError: Max retries (10) exceeded for LOOP data
May 21 12:15:30 raspberrypi weewx[2586]:     ****  Waiting 60 seconds then retrying...
May 21 12:16:30 raspberrypi weewx[2586]: engine: retrying...
May 21 12:16:30 raspberrypi weewx[2586]: engine: Using configuration file /etc/weewx/weewx.conf
May 21 12:16:30 raspberrypi weewx[2586]: engine: Initializing engine
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading station type AcuRite (weewx.drivers.acurite)
May 21 12:16:30 raspberrypi weewx[2586]: acurite: driver version is 0.17
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdTimeSynch
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdTimeSynch
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdConvert
May 21 12:16:30 raspberrypi weewx[2586]: engine: StdConvert target unit is 0x1
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdConvert
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdCalibrate
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdCalibrate
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdQC
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdQC
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.wxservices.StdWXCalculate
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.wxservices.StdWXCalculate
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdArchive
May 21 12:16:30 raspberrypi weewx[2586]: engine: Archive will use data binding wx_binding
May 21 12:16:30 raspberrypi weewx[2586]: engine: Record generation will be attempted in 'hardware'
May 21 12:16:30 raspberrypi weewx[2586]: engine: Using archive interval of 300 seconds
May 21 12:16:30 raspberrypi weewx[2586]: engine: Use LOOP data in hi/low calculations: 1
May 21 12:16:30 raspberrypi weewx[2586]: engine: Using binding 'wx_binding' to database '/var/lib/weewx/weewx.sdb'
May 21 12:16:30 raspberrypi weewx[2586]: engine: Starting backfill of daily summaries
May 21 12:16:30 raspberrypi weewx[2586]: engine: Daily summaries up to date.
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdArchive
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdStationRegistry
May 21 12:16:30 raspberrypi weewx[2586]: restx: StationRegistry: Data will not be posted. Missing option 'station_url'
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdStationRegistry
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdWunderground
May 21 12:16:30 raspberrypi weewx[2586]: restx: Wunderground-PWS: Data for station KILJOLIE17 will be posted
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdWunderground
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdPWSweather
May 21 12:16:30 raspberrypi weewx[2586]: restx: PWSWeather: Data will not be posted: Missing option 'station'
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdPWSweather
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdCWOP
May 21 12:16:30 raspberrypi weewx[2586]: restx: CWOP: Data will not be posted. Missing option: 'station'
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdCWOP
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdWOW
May 21 12:16:30 raspberrypi weewx[2586]: restx: WOW: Data will not be posted: Missing option 'station'
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdWOW
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdAWEKAS
May 21 12:16:30 raspberrypi weewx[2586]: restx: AWEKAS: Data will not be posted: Missing option 'username'
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdAWEKAS
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdPrint
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdPrint
May 21 12:16:30 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdReport
May 21 12:16:30 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdReport
May 21 12:16:30 raspberrypi weewx[2586]: engine: Starting up weewx version 3.1.0
May 21 12:16:30 raspberrypi weewx[2586]: engine: Station does not support reading the time
May 21 12:16:30 raspberrypi weewx[2586]: engine: Starting main packet loop.
May 21 12:16:30 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:16:30 raspberrypi weewx[2586]: acurite: Failed attempt 1 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:17:00 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:17:00 raspberrypi weewx[2586]: acurite: Set configuration failed: could not set config 1: Broken pipe
May 21 12:17:01 raspberrypi /USR/SBIN/CRON[2719]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
May 21 12:17:01 raspberrypi weewx[2586]: acurite: Failed attempt 2 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:17:01 raspberrypi kernel: [  459.411124] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:17:32 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:17:32 raspberrypi weewx[2586]: acurite: Failed attempt 3 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:18:02 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:18:03 raspberrypi weewx[2586]: acurite: Failed attempt 4 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:18:03 raspberrypi kernel: [  521.171415] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:18:33 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:18:34 raspberrypi weewx[2586]: acurite: Set configuration failed: could not set config 1: Broken pipe
May 21 12:18:34 raspberrypi weewx[2586]: acurite: Failed attempt 5 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:19:04 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:19:05 raspberrypi weewx[2586]: acurite: Failed attempt 6 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:19:05 raspberrypi kernel: [  582.931686] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:19:35 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:19:36 raspberrypi weewx[2586]: acurite: Failed attempt 7 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:19:36 raspberrypi kernel: [  613.951858] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:20:06 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:20:06 raspberrypi weewx[2586]: acurite: Failed attempt 8 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:20:36 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:20:37 raspberrypi weewx[2586]: acurite: Failed attempt 9 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:20:37 raspberrypi kernel: [  675.012203] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:21:07 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:21:09 raspberrypi weewx[2586]: acurite: Failed attempt 10 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:21:09 raspberrypi kernel: [  706.732299] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:21:39 raspberrypi weewx[2586]: acurite: Max retries (10) exceeded for LOOP data
May 21 12:21:39 raspberrypi weewx[2586]: restx: Shut down Wunderground-PWS thread.
May 21 12:21:39 raspberrypi weewx[2586]: engine: Caught WeeWxIOError: Max retries (10) exceeded for LOOP data
May 21 12:21:39 raspberrypi weewx[2586]:     ****  Waiting 60 seconds then retrying...
May 21 12:22:39 raspberrypi weewx[2586]: engine: retrying...
May 21 12:22:39 raspberrypi weewx[2586]: engine: Using configuration file /etc/weewx/weewx.conf
May 21 12:22:39 raspberrypi weewx[2586]: engine: Initializing engine
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading station type AcuRite (weewx.drivers.acurite)
May 21 12:22:39 raspberrypi weewx[2586]: acurite: driver version is 0.17
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdTimeSynch
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdTimeSynch
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdConvert
May 21 12:22:39 raspberrypi weewx[2586]: engine: StdConvert target unit is 0x1
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdConvert
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdCalibrate
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdCalibrate
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdQC
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdQC
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.wxservices.StdWXCalculate
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.wxservices.StdWXCalculate
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdArchive
May 21 12:22:39 raspberrypi weewx[2586]: engine: Archive will use data binding wx_binding
May 21 12:22:39 raspberrypi weewx[2586]: engine: Record generation will be attempted in 'hardware'
May 21 12:22:39 raspberrypi weewx[2586]: engine: Using archive interval of 300 seconds
May 21 12:22:39 raspberrypi weewx[2586]: engine: Use LOOP data in hi/low calculations: 1
May 21 12:22:39 raspberrypi weewx[2586]: engine: Using binding 'wx_binding' to database '/var/lib/weewx/weewx.sdb'
May 21 12:22:39 raspberrypi weewx[2586]: engine: Starting backfill of daily summaries
May 21 12:22:39 raspberrypi weewx[2586]: engine: Daily summaries up to date.
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdArchive
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdStationRegistry
May 21 12:22:39 raspberrypi weewx[2586]: restx: StationRegistry: Data will not be posted. Missing option 'station_url'
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdStationRegistry
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdWunderground
May 21 12:22:39 raspberrypi weewx[2586]: restx: Wunderground-PWS: Data for station KILJOLIE17 will be posted
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdWunderground
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdPWSweather
May 21 12:22:39 raspberrypi weewx[2586]: restx: PWSWeather: Data will not be posted: Missing option 'station'
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdPWSweather
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdCWOP
May 21 12:22:39 raspberrypi weewx[2586]: restx: CWOP: Data will not be posted. Missing option: 'station'
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdCWOP
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdWOW
May 21 12:22:39 raspberrypi weewx[2586]: restx: WOW: Data will not be posted: Missing option 'station'
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdWOW
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.restx.StdAWEKAS
May 21 12:22:39 raspberrypi weewx[2586]: restx: AWEKAS: Data will not be posted: Missing option 'username'
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.restx.StdAWEKAS
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdPrint
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdPrint
May 21 12:22:39 raspberrypi weewx[2586]: engine: Loading service weewx.engine.StdReport
May 21 12:22:39 raspberrypi weewx[2586]: engine: Finished loading service weewx.engine.StdReport
May 21 12:22:39 raspberrypi weewx[2586]: engine: Starting up weewx version 3.1.0
May 21 12:22:39 raspberrypi weewx[2586]: engine: Station does not support reading the time
May 21 12:22:39 raspberrypi weewx[2586]: engine: Starting main packet loop.
May 21 12:22:39 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:22:39 raspberrypi weewx[2586]: acurite: Set configuration failed: could not set config 1: Broken pipe
May 21 12:22:40 raspberrypi weewx[2586]: acurite: Failed attempt 1 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:22:40 raspberrypi kernel: [  797.932760] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:23:10 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:23:12 raspberrypi weewx[2586]: acurite: Failed attempt 2 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:23:12 raspberrypi kernel: [  829.652935] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:23:42 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:23:42 raspberrypi weewx[2586]: acurite: Set configuration failed: could not set config 1: Broken pipe
May 21 12:23:43 raspberrypi weewx[2586]: acurite: Failed attempt 3 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:23:43 raspberrypi kernel: [  861.373138] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:24:13 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:24:14 raspberrypi weewx[2586]: acurite: Failed attempt 4 of 10 to get LOOP data: error sending control message: Broken pipe
May 21 12:24:44 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:24:45 raspberrypi weewx[2586]: acurite: Failed attempt 5 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:24:45 raspberrypi kernel: [  923.133433] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:25:15 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:25:16 raspberrypi weewx[2586]: acurite: Set configuration failed: could not set config 1: Broken pipe
May 21 12:25:17 raspberrypi weewx[2586]: acurite: Failed attempt 6 of 10 to get LOOP data: error sending control message: Connection timed out
May 21 12:25:17 raspberrypi kernel: [  954.853543] usb 1-1.3: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -110
May 21 12:25:47 raspberrypi weewx[2586]: acurite: Found station at bus=001 device=004
May 21 12:25:48 raspberrypi weewx[2586]: acurite: Failed attempt 7 of 10 to get LOOP data: error sending control message: Broken pipe
pi@raspberrypi ~ $

-Keith

Keith Meiser

unread,
May 21, 2015, 1:55:16 PM5/21/15
to weewx...@googlegroups.com
I just shutdown the Raspberry and removed the power for 10 seconds and unplugged/replugged the USB before reapplying the power. It is still failing.

Keith Meiser

unread,
May 21, 2015, 2:33:44 PM5/21/15
to weewx...@googlegroups.com
Here is the USB initialization on boot before weewx starts (the AcuRite port):

Successful startup (weewx was able to communicate after starting):

May 21 11:43:36 raspberrypi kernel: [    3.549015] usb 1-1.3: new low-speed USB device number 4 using dwc_otg
May 21 11:43:36 raspberrypi kernel: [    3.664946] usb 1-1.3: New USB device found, idVendor=24c0, idProduct=0003
May 21 11:43:36 raspberrypi kernel: [    3.664958] usb 1-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0
May 21 11:43:36 raspberrypi kernel: [    3.664968] usb 1-1.3: Product: Chaney Instrument
May 21 11:43:36 raspberrypi kernel: [    4.453090] random: nonblocking pool is initialized
May 21 11:43:36 raspberrypi kernel: [   13.698765] hid-generic 0003:24C0:0003.0001: timeout initializing reports
May 21 11:43:36 raspberrypi kernel: [   13.710472] input: Chaney Instrument as /devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:24C0:0003.0001/input/input0
May 21 11:43:36 raspberrypi kernel: [   13.726751] hid-generic 0003:24C0:0003.0001: input,hidraw0: USB HID v1.11 Device [Chaney Instrument] on usb-bcm2708_usb-1.3/input0

Failed startup (weewx was not able to communicate after starting):

May 21 12:09:04 raspberrypi kernel: [    3.549073] usb 1-1.3: new low-speed USB device number 4 using dwc_otg
May 21 12:09:04 raspberrypi kernel: [    3.666762] usb 1-1.3: New USB device found, idVendor=24c0, idProduct=0003
May 21 12:09:04 raspberrypi kernel: [    3.677968] usb 1-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0
May 21 12:09:04 raspberrypi kernel: [    3.689970] usb 1-1.3: Product: Chaney Instrument
May 21 12:09:04 raspberrypi kernel: [    4.392416] random: nonblocking pool is initialized
May 21 12:09:04 raspberrypi kernel: [   13.708899] hid-generic 0003:24C0:0003.0001: usb_submit_urb(ctrl) failed: -1
May 21 12:09:04 raspberrypi kernel: [   13.720345] hid-generic 0003:24C0:0003.0001: timeout initializing reports
May 21 12:09:04 raspberrypi kernel: [   13.732403] input: Chaney Instrument as /devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:24C0:0003.0001/input/input0
May 21 12:09:04 raspberrypi kernel: [   13.749008] hid-generic 0003:24C0:0003.0001: input,hidraw0: USB HID v1.11 Device [Chaney Instrument] on usb-bcm2708_usb-1.3/input0


Keith Meiser

unread,
May 21, 2015, 3:10:46 PM5/21/15
to weewx...@googlegroups.com
Without shutting down, I moved the AcuRite USB from 1-1.3 to the original 1-1.4 (still failed with: usb_submit_urb(ctrl) failed: -1) and then to my last available port that has never had AcuRite on it, 1-1.2 and it was successful.
It is working again but I'm worried that it somehow "poisons" the port in the devices(?). Maybe this is a Rasperian problem or a Raspberry Pi 2 hardware problem.
I apologize for my ignorance. I am new to Linix and the rest here being an old AT&T Unix and Sun/Solaris guy, retired 4 years without keeping up.

mwall

unread,
May 21, 2015, 7:32:12 PM5/21/15
to weewx...@googlegroups.com, kem...@gmail.com
On Thursday, May 21, 2015 at 3:10:46 PM UTC-4, Keith Meiser wrote:
Without shutting down, I moved the AcuRite USB from 1-1.3 to the original 1-1.4 (still failed with: usb_submit_urb(ctrl) failed: -1) and then to my last available port that has never had AcuRite on it, 1-1.2 and it was successful.
It is working again but I'm worried that it somehow "poisons" the port in the devices(?). Maybe this is a Rasperian problem or a Raspberry Pi 2 hardware problem.


keith,

is  the acurite station plugged directly into the pi or into a usb hub that is attached to the pi?

which libusb is installed?

which pyusb is installed?

m

Keith Meiser

unread,
May 21, 2015, 11:48:41 PM5/21/15
to weewx...@googlegroups.com, kem...@gmail.com
AcuRite console is plugged directly into the Raspberry USB port. The only other port in use has the WiFi adapter. No hub. Power supply is 5v 2000ma.

Not sure how to check the packages so this may not help:

pi@raspberrypi ~ $ dpkg -l | grep libusb
ii  libusb-0.1-4:armhf                    2:0.1.12-20+nmu1                        armhf        userspace USB programming library
ii  libusb-1.0-0:armhf                    2:1.0.11-1                              armhf        userspace USB programming library
ii  libusbmuxd1                           1.0.7-2                                 armhf        USB multiplexor daemon for iPhone and iPod Touch devices - library

pi@raspberrypi ~ $ dpkg -S libusb
libusbmuxd1: /usr/lib/libusbmuxd.so.1.0.7
libusb-0.1-4:armhf: /usr/share/doc/libusb-0.1-4/changelog.Debian.gz
libusbmuxd1: /usr/share/doc/libusbmuxd1/changelog.Debian.gz
libusb-0.1-4:armhf: /usr/share/doc/libusb-0.1-4/README.Debian
libusb-0.1-4:armhf: /lib/arm-linux-gnueabihf/libusb-0.1.so.4.4.4
libusb-0.1-4:armhf: /lib/arm-linux-gnueabihf/libusb-0.1.so.4
libusb-1.0-0:armhf: /usr/share/doc/libusb-1.0-0/copyright
libusb-0.1-4:armhf: /usr/share/doc/libusb-0.1-4/copyright
libusb-1.0-0:armhf: /usr/share/doc/libusb-1.0-0
libusb-0.1-4:armhf: /usr/share/doc/libusb-0.1-4/changelog.gz
libusb-1.0-0:armhf: /lib/arm-linux-gnueabihf/libusb-1.0.so.0.1.0
libusb-1.0-0:armhf: /usr/share/doc/libusb-1.0-0/changelog.Debian.gz
libusb-0.1-4:armhf: /usr/share/doc/libusb-0.1-4
libusbmuxd1: /usr/share/doc/libusbmuxd1/copyright
libusb-1.0-0:armhf: /usr/share/doc/libusb-1.0-0/README
libusbmuxd1: /usr/share/doc/libusbmuxd1
libusbmuxd1: /usr/lib/libusbmuxd.so.1
libusb-1.0-0:armhf: /lib/arm-linux-gnueabihf/libusb-1.0.so.0
libusb-1.0-0:armhf: /usr/share/doc/libusb-1.0-0/changelog.gz

pi@raspberrypi ~ $ dpkg -L libusb-0.1-4
/.
/lib
/lib/arm-linux-gnueabihf
/lib/arm-linux-gnueabihf/libusb-0.1.so.4.4.4
/usr
/usr/share
/usr/share/doc
/usr/share/doc/libusb-0.1-4
/usr/share/doc/libusb-0.1-4/changelog.Debian.gz
/usr/share/doc/libusb-0.1-4/README.Debian
/usr/share/doc/libusb-0.1-4/copyright
/usr/share/doc/libusb-0.1-4/changelog.gz
/lib/arm-linux-gnueabihf/libusb-0.1.so.4



pi@raspberrypi ~ $ sudo find / -name pyusb*
/usr/share/pyshared/pyusb-0.4.3.egg-info
/usr/lib/python2.7/dist-packages/pyusb-0.4.3.egg-info
/usr/lib/python2.6/dist-packages/pyusb-0.4.3.egg-info

-Keith
Reply all
Reply to author
Forward
0 new messages