Wifi DHCP failure

All about WTware on Raspberry. Wtware works with Raspberry Pi 3 Model B and Pi 2 Model B devices
http://www.winterminal.com
Post Reply
RonM
Posts: 6
Joined: Thu Jul 05, 2018 11:18 pm

Wifi DHCP failure

Post by RonM » Fri Jul 06, 2018 2:30 pm

For some reason all of my pi's both pi3 and pi3b+ have stopped pulling and IP from DHCP. Ethernet seems to be fine. I have tried 5.8.18 20 and 22. I get "No response from DHCP"

All other devices on the wireless network pull ip's fine. Even Raspian works on the pi that wtware doesn't work on.

It is Windows DHCP. I have given static IP's and they work just not DHCP and just on wireless.

RonM
Posts: 6
Joined: Thu Jul 05, 2018 11:18 pm

Re: Wifi DHCP failure

Post by RonM » Fri Jul 06, 2018 10:34 pm

As a followup, this is now affecting all of our wtware devices. For some reason none of our wtware pi's can grab DHCP. Not sure whats going on.

aka
SUPPORT
SUPPORT
Posts: 636
Joined: Fri Dec 03, 2004 2:05 pm
Contact:

Re: Wifi DHCP failure

Post by aka » Sat Jul 07, 2018 3:08 pm

https://wtware.com/logs.html

Last part "when terminal failed to connect to network".

Perhaps, something weird in "vendor class" dhcp configuration? Search for "vendor class" in this manual https://wtware.com/win/dhcp2012config.html and make similar screenshots of your dhcp configuration.

RonM
Posts: 6
Joined: Thu Jul 05, 2018 11:18 pm

Re: Wifi DHCP failure

Post by RonM » Mon Jul 09, 2018 4:34 pm

My DHCP is configured as described in the setup page. I am not using PXE boot.

The log is showing the below. The part that interests me is Skip 'psk='.

/--- FILE "/etc/wpa.conf" -----------------------
| ctrl_interface=/var/run/wpa_supplicant
| network={
Skip 'psk='.
| ssid="GGI-Office"
| }
\----------------------------------------------------
[ initrd] [ 15.077621] +--- Executing "/sbin/wpa_supplicant -B -c /etc/wpa.conf -i eth0 -f /tmp/wpa_supplicant.out"
[ WPA] [ 15.202083] Successfully initialized wpa_supplicant
[ WPA] [ 15.208183] rfkill: Cannot open RFKILL control device
[ KERNEL] [ 15.242348] brcmfmac: power management disabled
[ WPA] [ 15.449861] rfkill: Cannot open RFKILL control device
[ initrd] [ 15.473379] +- Errorlevel: 0, output:
File is empty.
[ initrd] [ 15.473891] +------------------------
[ initrd] [ 15.505101] State SCANNING before handshake, do nothing.
[ initrd] [ 15.813003] State SCANNING before handshake, do nothing.
[ initrd] [ 16.120766] State SCANNING before handshake, do nothing.
[ initrd] [ 16.428576] State SCANNING before handshake, do nothing.
[ initrd] [ 16.736215] State SCANNING before handshake, do nothing.
[ initrd] [ 17.043838] State SCANNING before handshake, do nothing.
[ initrd] [ 17.351257] State SCANNING before handshake, do nothing.
[ initrd] [ 17.658824] State SCANNING before handshake, do nothing.
[ initrd] [ 17.966304] State SCANNING before handshake, do nothing.
[ WPA] [ 18.189567] eth0: Trying to associate with 40:e3:d6:b0:95:90 (SSID='GGI-Office' freq=5280 MHz)
[ WPA] [ 18.190637] FT: Invalid group cipher (0)
[ initrd] [ 18.274224] State ASSOCIATING before handshake, do nothing.
[ WPA] [ 18.387380] eth0: Associated with 40:e3:d6:b0:95:90
[ WPA] [ 18.397215] eth0: WPA: Key negotiation completed with 40:e3:d6:b0:95:90 [PTK=CCMP GTK=CCMP]
[ WPA] [ 18.397338] eth0: CTRL-EVENT-CONNECTED - Connection to 40:e3:d6:b0:95:90 completed [id=0 id_str=]
[ initrd] [ 18.582183] wpa_state=COMPLETED

akatik
SUPPORT
SUPPORT
Posts: 277
Joined: Mon Jan 17, 2005 6:30 pm
Contact:

Re: Wifi DHCP failure

Post by akatik » Wed Jul 11, 2018 9:52 am

RonM wrote:
Mon Jul 09, 2018 4:34 pm
The log is showing the below. The part that interests me is Skip 'psk='.
It is not log but small part of log. I need full log.

'psk=' is a wifi poassword. We do not publish it into log.

"wpa_state=COMPLETED" - wifi password is right, it connects to wifi access point. Later it should ask DHCP and print to log if receives some reply.

RonM
Posts: 6
Joined: Thu Jul 05, 2018 11:18 pm

Re: Wifi DHCP failure

Post by RonM » Wed Jul 11, 2018 4:25 pm

Below is the full log. I also included the capture from my Aruba Access Point. After significant time spent troubleshooting this I have concluded that it is definitely something with wtware and my wireless. And only on newly built pi's. The old pi's are getting IP's from DHCP. No other devices are having DHCP problems.

WTware 5.8.22/RPi supervisor.
[ KERNEL] [ 0.000000] Booting Linux on physical CPU 0x0
[ KERNEL] [ 0.000000] Linux version 4.14.50 (aka@c20) (gcc version 6.3.0 20170516 (Debian 6.3.0-18)) #4 SMP Wed Jun 27 06:33:15 UTC 2018
[ KERNEL] [ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[ KERNEL] [ 0.000000] CPU: div instructions available: patching division code
[ KERNEL] [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[ KERNEL] [ 0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
[ KERNEL] [ 0.000000] Memory policy: Data cache writealloc
[ KERNEL] [ 0.000000] cma: Reserved 8 MiB at 0x3ac00000
[ KERNEL] [ 0.000000] On node 0 totalpages: 242688
[ KERNEL] [ 0.000000] free_area_init_node: node 0, pgdat 80b24500, node_mem_map ba3a1000
[ KERNEL] [ 0.000000] Normal zone: 2133 pages used for memmap
[ KERNEL] [ 0.000000] Normal zone: 0 pages reserved
[ KERNEL] [ 0.000000] Normal zone: 242688 pages, LIFO batch:31
[ KERNEL] [ 0.000000] percpu: Embedded 15 pages/cpu @ba352000 s28684 r8192 d24564 u61440
[ KERNEL] [ 0.000000] pcpu-alloc: s28684 r8192 d24564 u61440 alloc=15*4096
[ KERNEL] [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[ KERNEL] [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 240555
[ KERNEL] [ 0.000000] Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 bcm2708_fb.fbdepth=32 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 hide_setup dwc_otg.lpm_enable=0 quiet wtboot=mmc
[ KERNEL] [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[ KERNEL] [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[ KERNEL] [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[ KERNEL] [ 0.000000] Memory: 942080K/970752K available (5120K kernel code, 150K rwdata, 652K rodata, 4096K init, 367K bss, 20480K reserved, 8192K cma-reserved)
[ KERNEL] [ 0.000000] Virtual kernel memory layout:
[ KERNEL] [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ KERNEL] [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
[ KERNEL] [ 0.000000] vmalloc : 0xbb800000 - 0xff800000 (1088 MB)
[ KERNEL] [ 0.000000] lowmem : 0x80000000 - 0xbb400000 ( 948 MB)
[ KERNEL] [ 0.000000] modules : 0x7f000000 - 0x80000000 ( 16 MB)
[ KERNEL] [ 0.000000] .text : 0x80008000 - 0x80600000 (6112 kB)
[ KERNEL] [ 0.000000] .init : 0x80700000 - 0x80b00000 (4096 kB)
[ KERNEL] [ 0.000000] .data : 0x80b00000 - 0x80b25a60 ( 151 kB)
[ KERNEL] [ 0.000000] .bss : 0x80b27000 - 0x80b82eb4 ( 368 kB)
[ KERNEL] [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ KERNEL] [ 0.000000] Hierarchical RCU implementation.
[ KERNEL] [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ KERNEL] [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[ KERNEL] [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[ KERNEL] [ 0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[ KERNEL] [ 0.000017] Switching to timer-based delay loop, resolution 52ns
[ KERNEL] [ 0.000200] Console: colour dummy device 80x30
[ KERNEL] [ 0.000215] console [tty0] enabled
[ KERNEL] [ 0.000236] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[ KERNEL] [ 0.000249] pid_max: default: 32768 minimum: 301
[ KERNEL] [ 0.000408] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ KERNEL] [ 0.000422] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ KERNEL] [ 0.001290] Disabling memory control group subsystem
[ KERNEL] [ 0.001350] CPU: Testing write buffer coherency: ok
[ KERNEL] [ 0.001699] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ KERNEL] [ 0.002018] Setting up static identity map for 0x100000 - 0x10003c
[ KERNEL] [ 0.002123] Hierarchical SRCU implementation.
[ KERNEL] [ 0.002644] smp: Bringing up secondary CPUs ...
[ KERNEL] [ 0.003216] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ KERNEL] [ 0.003858] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ KERNEL] [ 0.004464] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ KERNEL] [ 0.004548] smp: Brought up 1 node, 4 CPUs
[ KERNEL] [ 0.004558] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[ KERNEL] [ 0.004562] CPU: All CPU(s) started in HYP mode.
[ KERNEL] [ 0.004565] CPU: Virtualization extensions available.
[ KERNEL] [ 0.005143] devtmpfs: initialized
[ KERNEL] [ 0.013869] random: get_random_u32 called from 0x802d6f94 with crng_init=0
[ KERNEL] [ 0.014276] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[ KERNEL] [ 0.014610] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ KERNEL] [ 0.014625] futex hash table entries: 1024 (order: 4, 65536 bytes)
[ KERNEL] [ 0.015128] pinctrl core: initialized pinctrl subsystem
[ KERNEL] [ 0.015572] NET: Registered protocol family 16
[ KERNEL] [ 0.017651] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ KERNEL] [ 0.023976] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[ KERNEL] [ 0.061639] bcm2835-dma 3f007000.dma: DMA legacy API manager at bb813000, dmachans=0x1
[ KERNEL] [ 0.063494] usbcore: registered new interface driver usbfs
[ KERNEL] [ 0.063600] usbcore: registered new interface driver hub
[ KERNEL] [ 0.063682] usbcore: registered new device driver usb
[ KERNEL] [ 0.070155] raspberrypi-firmware soc:firmware: Attached to firmware from 2018-06-07 15:31
[ KERNEL] [ 0.072052] clocksource: Switched to clocksource arch_sys_counter
[ KERNEL] [ 0.083138] NET: Registered protocol family 2
[ KERNEL] [ 0.083670] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[ KERNEL] [ 0.083779] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[ KERNEL] [ 0.083963] TCP: Hash tables configured (established 8192 bind 8192)
[ KERNEL] [ 0.084081] UDP hash table entries: 512 (order: 2, 16384 bytes)
[ KERNEL] [ 0.084124] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[ KERNEL] [ 0.084319] NET: Registered protocol family 1
[ KERNEL] [ 2.208552] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[ KERNEL] [ 2.263672] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[ KERNEL] [ 2.263685] io scheduler noop registered
[ KERNEL] [ 2.263691] io scheduler deadline registered
[ KERNEL] [ 2.263953] io scheduler cfq registered (default)
[ KERNEL] [ 2.263960] io scheduler mq-deadline registered
[ KERNEL] [ 2.263965] io scheduler kyber registered
[ KERNEL] [ 2.267072] BCM2708FB: allocated DMA memory fad00000
[ KERNEL] [ 2.267122] BCM2708FB: allocated DMA channel 0 @ bb813000
[ KERNEL] [ 2.320866] Console: switching to colour frame buffer device 240x67
[ KERNEL] [ 2.352973] bcm2835-rng 3f104000.rng: hwrng registered
[ KERNEL] [ 2.353208] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[ KERNEL] [ 2.354008] vc-sm: Videocore shared memory driver
[ KERNEL] [ 2.354644] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[ KERNEL] [ 2.355832] zram: Added device: zram0
[ KERNEL] [ 2.355870] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ KERNEL] [ 2.584086] Core Release: 2.80a
[ KERNEL] [ 2.584100] Setting default values for core params
[ KERNEL] [ 2.584127] Finished setting default values for core params
[ KERNEL] [ 2.784390] Using Buffer DMA mode
[ KERNEL] [ 2.784395] Periodic Transfer Interrupt Enhancement - disabled
[ KERNEL] [ 2.784400] Multiprocessor Interrupt Enhancement - disabled
[ KERNEL] [ 2.784406] OTG VER PARAM: 0, OTG VER FLAG: 0
[ KERNEL] [ 2.784441] Dedicated Tx FIFOs mode
[ KERNEL] [ 2.784972] WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xbad14000 dma = 0xfad14000 len=9024
[ KERNEL] [ 2.785000] FIQ FSM acceleration enabled for :
[ KERNEL] [ 2.785000] Non-periodic Split Transactions
[ KERNEL] [ 2.785000] Periodic Split Transactions
[ KERNEL] [ 2.785000] High-Speed Isochronous Endpoints
[ KERNEL] [ 2.785000] Interrupt/Control Split Transaction hack enabled
[ KERNEL] [ 2.785007] dwc_otg: Microframe scheduler enabled
[ KERNEL] [ 2.785058] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x8039e15c
[ KERNEL] [ 2.785068] WARN::hcd_init_fiq:460: FIQ ASM at 0x8039e4b4 length 36
[ KERNEL] [ 2.785078] WARN::hcd_init_fiq:486: MPHI regs_base at 0xbb929000
[ KERNEL] [ 2.785133] dwc_otg 3f980000.usb: DWC OTG Controller
[ KERNEL] [ 2.785167] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[ KERNEL] [ 2.785199] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
[ KERNEL] [ 2.785246] Init: Port Power? op_state=1
[ KERNEL] [ 2.785251] Init: Power Port (0)
[ KERNEL] [ 2.785437] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ KERNEL] [ 2.785447] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ KERNEL] [ 2.785454] usb usb1: Product: DWC OTG Controller
[ KERNEL] [ 2.785462] usb usb1: Manufacturer: Linux 4.14.50 dwc_otg_hcd
[ KERNEL] [ 2.785469] usb usb1: SerialNumber: 3f980000.usb
[ KERNEL] [ 2.786064] hub 1-0:1.0: USB hub found
[ KERNEL] [ 2.786104] hub 1-0:1.0: 1 port detected
[ KERNEL] [ 2.786802] dwc_otg: FIQ enabled
[ KERNEL] [ 2.786812] dwc_otg: NAK holdoff enabled
[ KERNEL] [ 2.786822] dwc_otg: FIQ split-transaction FSM enabled
[ KERNEL] [ 2.786838] Module dwc_common_port init
[ KERNEL] [ 2.788321] usbcore: registered new interface driver usbtouchscreen
[ KERNEL] [ 2.789398] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[ KERNEL] [ 2.789839] bcm2835-cpufreq: min=600000 max=1400000
[ KERNEL] [ 2.790373] sdhci: Secure Digital Host Controller Interface driver
[ KERNEL] [ 2.790378] sdhci: Copyright(c) Pierre Ossman
[ KERNEL] [ 2.790929] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
[ KERNEL] [ 2.791448] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
[ KERNEL] [ 2.791586] sdhci-pltfm: SDHCI platform and OF driver helper
[ KERNEL] [ 2.794520] ledtrig-cpu: registered to indicate activity on CPUs
[ KERNEL] [ 2.794699] hidraw: raw HID events driver (C) Jiri Kosina
[ KERNEL] [ 2.795164] usbcore: registered new interface driver usbhid
[ KERNEL] [ 2.795171] usbhid: USB HID core driver
[ KERNEL] [ 2.795872] vchiq: vchiq_init_state: slot_zero = bad80000, is_master = 0
[ KERNEL] [ 2.808050] [vc_sm_connected_init]: start
[ KERNEL] [ 2.815977] [vc_sm_connected_init]: end - returning 0
[ KERNEL] [ 2.816416] NET: Registered protocol family 17
[ KERNEL] [ 2.816563] Registering SWP/SWPB emulation handler
[ KERNEL] [ 2.816951] registered taskstats version 1
[ KERNEL] [ 2.822218] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
[ KERNEL] [ 2.822232] mmc-bcm2835 3f300000.mmc: DMA channel allocated
[ KERNEL] [ 2.893607] sdhost: log_buf @ bad13000 (fad13000)
[ KERNEL] [ 2.929654] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ KERNEL] [ 2.931149] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ KERNEL] [ 2.932645] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ KERNEL] [ 2.935308] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ KERNEL] [ 2.972079] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[ KERNEL] [ 2.973196] of_cfs_init
[ KERNEL] [ 2.973296] of_cfs_init: OK
[ KERNEL] [ 2.992593] Freeing unused kernel memory: 4096K
[ KERNEL] [ 3.015216] Indeed it is in host mode hprt0 = 00021501
[ KERNEL] [ 3.031227] random: fast init done
[ KERNEL] [ 3.038249] mmc1: new high speed SDIO card at address 0001
[ KERNEL] [ 3.058742] mmc0: host does not support reading read-only switch, assuming write-enable
[ KERNEL] [ 3.061328] mmc0: new high speed SDHC card at address 0001
[ KERNEL] [ 3.061712] mmcblk0: mmc0:0001 EB1QT 29.8 GiB
[ KERNEL] [ 3.078263] mmcblk0: p1
[ hotplug] [ 3.078372] Block device 179:0. No action.
[ hotplug] [ 3.078543] Block device 179:1. No action.
[ KERNEL] [ 3.222390] usb 1-1: new high-speed USB device number 2 using dwc_otg
[ KERNEL] [ 3.222523] Indeed it is in host mode hprt0 = 00001101
[ hotplug] [ 3.265746] Block device 254:0. No action.
[ pfac] [ 3.347126] Run /sbin/initrd.
[ pfac] [ 3.347376] Ok, PID 630.
[ initrd] [ 3.352298] WTware 5.8.22/RPi shell.
[ initrd] [ 3.352775] Load font 'ter-c32b.psf', console 120x33.
[ initrd] [ 3.385832] MMC boot.
[ initrd] [ 3.388593] Success: media /dev/mmcblk0p1 mounted to /bootmedia.
/--- FILE "/bootmedia/config.txt" -----------------------
| # For more options and information see:
| #
| # https://www.raspberrypi.org/documentati ... /README.md
| #
| # Some settings may impact device functionality. See link above for details.
| # Uncomment if you get no picture on HDMI for a default "safe" mode.
| #hdmi_safe=1
| # Uncomment this if your display has a black border of unused pixels visible
| # and your display can output without overscan.
| disable_overscan=1
| # Uncomment if hdmi display is not detected and composite is being output.
| #hdmi_force_hotplug=1
| # Uncomment to force a specific HDMI mode (this will force VGA).
| #hdmi_group=1
| #hdmi_mode=1
| # Force the monitor to HDMI mode so that sound will be sent over HDMI cable.
| #hdmi_drive=2
| # Uncomment to increase signal to HDMI, if you have interference, blanking, or
| # no display.
| #config_hdmi_boost=4
| # Uncomment to overclock the arm. 700 MHz is the default.
| #arm_freq=800
| disable_splash=1
| #hdmi_group=2
| # 800x600: hdmi_mode=9
| # 1024x768: hdmi_mode=16
| # 1280x1024: hdmi_mode=35
| # 1360x768: hdmi_mode=39
| # 1400x1050: hdmi_mode=42
| # 1440x900: hdmi_mode=47
| # 1600x1200: hdmi_mode=51
| # 1680x1050: hdmi_mode=58
| # 1366x768: hdmi_mode=81
| # 1920x1080: hdmi_mode=82
| # 1920x1200: hdmi_mode=69
| framebuffer_depth=32
| # Uncomment for 16-bit color. It is up to two
| # times faster than default 32-bit.
| #framebuffer_depth=16
| # Enable onboard audio (HDMI or jack).
| dtparam=audio=on
| # Removes the warning overlay.
| #avoid_warnings=1
| # Additionally allows turbo when low-voltage is present.
| #avoid_warnings=2
| hdmi_group=2
| hdmi_mode=82
\----------------------------------------------------
[ KERNEL] [ 3.462331] usb 1-1: New USB device found, idVendor=0424, idProduct=2514
[ KERNEL] [ 3.462343] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ KERNEL] [ 3.463024] hub 1-1:1.0: USB hub found
[ KERNEL] [ 3.463134] hub 1-1:1.0: 4 ports detected
[ KERNEL] [ 3.782083] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[ KERNEL] [ 3.912315] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514
[ KERNEL] [ 3.912327] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ KERNEL] [ 3.912951] hub 1-1.1:1.0: USB hub found
[ KERNEL] [ 3.913084] hub 1-1.1:1.0: 3 ports detected
[ KERNEL] [ 4.012083] usb 1-1.2: new low-speed USB device number 4 using dwc_otg
[ KERNEL] [ 4.168411] usb 1-1.2: New USB device found, idVendor=03f0, idProduct=2f4a
[ KERNEL] [ 4.168424] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ KERNEL] [ 4.168432] usb 1-1.2: Product: HP Business Slim Keyboard
[ KERNEL] [ 4.168439] usb 1-1.2: Manufacturer: Chicony
[ KERNEL] [ 4.182930] input: Chicony HP Business Slim Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/0003:03F0:2F4A.0001/input/input0
[ KERNEL] [ 4.253043] hid-generic 0003:03F0:2F4A.0001: input,hidraw0: USB HID v1.10 Keyboard [Chicony HP Business Slim Keyboard] on usb-3f980000.usb-1.2/input0
[ KERNEL] [ 4.271441] input: Chicony HP Business Slim Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.1/0003:03F0:2F4A.0002/input/input1
[ KERNEL] [ 4.332722] hid-generic 0003:03F0:2F4A.0002: input,hiddev96,hidraw1: USB HID v1.10 Device [Chicony HP Business Slim Keyboard] on usb-3f980000.usb-1.2/input1
[ initrd] [ 4.402504] Cache package files: auto.
[ initrd] [ 4.402768] WTpassword present: 930b9a9f9358e4c4645596b2dcdb3e01.
[ initrd] [ 4.405171] Cache package files.
[ KERNEL] [ 4.432089] usb 1-1.3: new low-speed USB device number 5 using dwc_otg
[ initrd] [ 4.471750] USB: 0424:2514 Standard Microsystems Corp. USB 2.0 Hub.
[ initrd] [ 4.471815] USB: 03f0:2f4a HP, Inc.
[ KERNEL] [ 4.567173] usb 1-1.3: New USB device found, idVendor=03f0, idProduct=094a
[ KERNEL] [ 4.567186] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ KERNEL] [ 4.567194] usb 1-1.3: Product: HP USB Optical Mouse
[ KERNEL] [ 4.567201] usb 1-1.3: Manufacturer: PixArt
[ KERNEL] [ 4.571504] input: PixArt HP USB Optical Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:03F0:094A.0003/input/input2
[ KERNEL] [ 4.571976] hid-generic 0003:03F0:094A.0003: input,hidraw2: USB HID v1.11 Mouse [PixArt HP USB Optical Mouse] on usb-3f980000.usb-1.3/input0
[ KERNEL] [ 4.822084] usb 1-1.1.1: new high-speed USB device number 6 using dwc_otg
[ KERNEL] [ 4.952445] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800
[ KERNEL] [ 4.952456] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
/--- FILE "/bootmedia/configs/initrd.wtc" -----------------------
| [BOM]
| netmedia=wifi
| config=http
| tftpPrefix=/
| clientIP=*
| tftpIP=10.10.7.29
| httpPort=81
| setupPassword=930b9a9f9358e4c4645596b2dcdb3e01
| wifi_ssid=GGI-Office
| wifi_channel=36
| wifi_security=wpa_psk
Skip 'wifi_key='.
\----------------------------------------------------
[ initrd] [ 6.434625] Load WiFi wireless network card driver.
[ initrd] [ 6.445988] [FILE CACHE] Take 'packages/pi2-dbus' from cache.
[ initrd] [ 6.446056] +--- Executing "/usr/bin/dbus-uuidgen --ensure"
[ initrd] [ 6.483205] +- Errorlevel: 0, output:
[ KERNEL] [ 6.483756] random: dbus-uuidgen: uninitialized urandom read (12 bytes read)
[ KERNEL] [ 6.483815] random: dbus-uuidgen: uninitialized urandom read (8 bytes read)
File is empty.
[ initrd] [ 6.483586] +------------------------
[ initrd] [ 6.483661] +--- Executing "/usr/bin/dbus-daemon --system"
[ KERNEL] [ 6.528507] random: dbus-daemon: uninitialized urandom read (12 bytes read)
[ initrd] [ 6.551246] +- Errorlevel: 0, output:
File is empty.
[ initrd] [ 6.551411] +------------------------
[ initrd] [ 6.761438] [FILE CACHE] Take 'packages/pi2-xnet' from cache.
[ initrd] [ 6.795428] Find SDIO device 'sdio:c02v02D0dA9A6'.
[ initrd] [ 6.795523] Load driver for Pi 3 onboard WiFi.
[ initrd] [ 6.795564] modprobe brcmfmac.
[ KERNEL] [ 6.808939] brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43455-sdio.bin for chip 0x004345(17221) rev 0x000006
[ KERNEL] [ 6.937782] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04
[ KERNEL] [ 6.938173] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 9.10.105 Compiler: 1.29.4 ClmImport: 1.36.3 Creation: 2018-03-09 18:56:28
[ KERNEL] [ 7.007204] brcmfmac mmc1:0001:1 eth0: renamed from wlan0
[ initrd] [ 7.060502] setup-wifi.cpp ( 512): -- ERROR -- INTERNAL ERROR. Please, contact WTware tech support.
[ KERNEL] [ 7.062346] brcmfmac: power management disabled
/--- FILE "/etc/wpa.conf" -----------------------
| ctrl_interface=/var/run/wpa_supplicant
| network={
Skip 'psk='.
| ssid="GGI-Office"
| }
\----------------------------------------------------
[ initrd] [ 7.065607] +--- Executing "/sbin/wpa_supplicant -B -c /etc/wpa.conf -i eth0 -f /tmp/wpa_supplicant.out"
[ WPA] [ 7.190325] Successfully initialized wpa_supplicant
[ WPA] [ 7.196416] rfkill: Cannot open RFKILL control device
[ KERNEL] [ 7.230477] brcmfmac: power management disabled
[ WPA] [ 7.449143] rfkill: Cannot open RFKILL control device
[ initrd] [ 7.472623] +- Errorlevel: 0, output:
File is empty.
[ initrd] [ 7.472969] +------------------------
[ initrd] [ 7.504207] State SCANNING before handshake, do nothing.
[ initrd] [ 7.811829] State SCANNING before handshake, do nothing.
[ initrd] [ 8.119442] State SCANNING before handshake, do nothing.
[ initrd] [ 8.427288] State SCANNING before handshake, do nothing.
[ initrd] [ 8.735117] State SCANNING before handshake, do nothing.
[ initrd] [ 9.042739] State SCANNING before handshake, do nothing.
[ initrd] [ 9.350550] State SCANNING before handshake, do nothing.
[ initrd] [ 9.658314] State SCANNING before handshake, do nothing.
[ initrd] [ 9.966120] State SCANNING before handshake, do nothing.
[ KERNEL] [ 10.245877] urandom_read: 5 callbacks suppressed
[ KERNEL] [ 10.245889] random: wpa_supplicant: uninitialized urandom read (4096 bytes read)
[ WPA] [ 10.244650] eth0: Trying to associate with 40:e3:d6:b0:95:90 (SSID='GGI-Office' freq=5280 MHz)
[ WPA] [ 10.244782] FT: Invalid group cipher (0)
[ initrd] [ 10.274038] State ASSOCIATING before handshake, do nothing.
[ WPA] [ 10.443966] eth0: Associated with 40:e3:d6:b0:95:90
[ KERNEL] [ 10.446160] random: wpa_supplicant: uninitialized urandom read (4096 bytes read)
[ WPA] [ 10.453351] eth0: WPA: Key negotiation completed with 40:e3:d6:b0:95:90 [PTK=CCMP GTK=CCMP]
[ WPA] [ 10.453470] eth0: CTRL-EVENT-CONNECTED - Connection to 40:e3:d6:b0:95:90 completed [id=0 id_str=]
[ initrd] [ 10.583356] wpa_state=COMPLETED
[ initrd] [ 10.583477] Download configs from HTTP 10.10.7.29 port 81 prefix '/'.
[ initrd] [ 10.583550] WTpassword present: 930b9a9f9358e4c4645596b2dcdb3e01.
[ initrd] [ 44.280188] Failed to read ethtool settings.
[ KERNEL] [ 52.836927] random: crng init done
[ KERNEL] [ 64.612199] usb 1-1.3: USB disconnect, device number 5
[ KERNEL] [ 66.192089] usb 1-1.3: new low-speed USB device number 7 using dwc_otg
[ KERNEL] [ 66.327162] usb 1-1.3: New USB device found, idVendor=03f0, idProduct=094a
[ KERNEL] [ 66.327174] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ KERNEL] [ 66.327181] usb 1-1.3: Product: HP USB Optical Mouse
[ KERNEL] [ 66.327189] usb 1-1.3: Manufacturer: PixArt
[ KERNEL] [ 66.331556] input: PixArt HP USB Optical Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:03F0:094A.0004/input/input3
[ KERNEL] [ 66.331999] hid-generic 0003:03F0:094A.0004: input,hidraw2: USB HID v1.11 Mouse [PixArt HP USB Optical Mouse] on usb-3f980000.usb-1.3/input0
[ KERNEL] [ 126.564222] usb 1-1.3: USB disconnect, device number 7
[ KERNEL] [ 128.142085] usb 1-1.3: new low-speed USB device number 8 using dwc_otg
[ KERNEL] [ 128.277165] usb 1-1.3: New USB device found, idVendor=03f0, idProduct=094a
[ KERNEL] [ 128.277176] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ KERNEL] [ 128.277184] usb 1-1.3: Product: HP USB Optical Mouse
[ KERNEL] [ 128.277192] usb 1-1.3: Manufacturer: PixArt
[ KERNEL] [ 128.281506] input: PixArt HP USB Optical Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:03F0:094A.0005/input/input4
[ KERNEL] [ 128.281922] hid-generic 0003:03F0:094A.0005: input,hidraw2: USB HID v1.11 Mouse [PixArt HP USB Optical Mouse] on usb-3f980000.usb-1.3/input0
[ KERNEL] [ 188.516228] usb 1-1.3: USB disconnect, device number 8
[ KERNEL] [ 190.092091] usb 1-1.3: new low-speed USB device number 9 using dwc_otg
[ KERNEL] [ 190.227161] usb 1-1.3: New USB device found, idVendor=03f0, idProduct=094a
[ KERNEL] [ 190.227172] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ KERNEL] [ 190.227180] usb 1-1.3: Product: HP USB Optical Mouse
[ KERNEL] [ 190.227187] usb 1-1.3: Manufacturer: PixArt
[ KERNEL] [ 190.231411] input: PixArt HP USB Optical Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:03F0:094A.0006/input/input5
[ KERNEL] [ 190.232569] hid-generic 0003:03F0:094A.0006: input,hidraw2: USB HID v1.11 Mouse [PixArt HP USB Optical Mouse] on usb-3f980000.usb-1.3/input0
[ KERNEL] [ 250.468234] usb 1-1.3: USB disconnect, device number 9
[ KERNEL] [ 252.042090] usb 1-1.3: new low-speed USB device number 10 using dwc_otg
[ KERNEL] [ 252.177165] usb 1-1.3: New USB device found, idVendor=03f0, idProduct=094a
[ KERNEL] [ 252.177176] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ KERNEL] [ 252.177185] usb 1-1.3: Product: HP USB Optical Mouse
[ KERNEL] [ 252.177192] usb 1-1.3: Manufacturer: PixArt
[ KERNEL] [ 252.181498] input: PixArt HP USB Optical Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:03F0:094A.0007/input/input6
[ KERNEL] [ 252.182558] hid-generic 0003:03F0:094A.0007: input,hidraw2: USB HID v1.11 Mouse [PixArt HP USB Optical Mouse] on usb-3f980000.usb-1.3/input0
[ KERNEL] [ 275.102090] usb 1-1.1.3: new high-speed USB device number 11 using dwc_otg
[ KERNEL] [ 275.349677] usb 1-1.1.3: New USB device found, idVendor=058f, idProduct=6387
[ KERNEL] [ 275.349688] usb 1-1.1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ KERNEL] [ 275.349696] usb 1-1.1.3: Product: Mass Storage
[ KERNEL] [ 275.349704] usb 1-1.1.3: Manufacturer: Generic
[ KERNEL] [ 275.349711] usb 1-1.1.3: SerialNumber: 1A699FD1
[ KERNEL] [ 279.248724] SCSI subsystem initialized
[ KERNEL] [ 279.257620] usb-storage 1-1.1.3:1.0: USB Mass Storage device detected
[ KERNEL] [ 279.257796] scsi host0: usb-storage 1-1.1.3:1.0
[ KERNEL] [ 279.258050] usbcore: registered new interface driver usb-storage
[ KERNEL] [ 280.314083] scsi 0:0:0:0: Direct-Access Generic Flash Disk 8.07 PQ: 0 ANSI: 4
[ KERNEL] [ 280.315944] sd 0:0:0:0: [sda] 7866368 512-byte logical blocks: (4.03 GB/3.75 GiB)
[ KERNEL] [ 280.316753] sd 0:0:0:0: [sda] Write Protect is off
[ KERNEL] [ 280.316767] sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
[ KERNEL] [ 280.317598] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ KERNEL] [ 280.323003] sda: sda1
[ KERNEL] [ 280.326207] sd 0:0:0:0: [sda] Attached SCSI removable disk
[ KERNEL] [ 289.306493] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.

###Aruba Access Point Log###
##WTware DHCP Fail##
Received packet from aruba000 (timestamp (2018-7-11 07:13:08:950107))
[asap_firewall_forward(5700):firewall entry] len 590, vlan 0, egress CP, ingress aruba000:
#mac: etype 0800 smac b8:27:eb:1d:32:36 dmac ff:ff:ff:ff:ff:ff
#ip: sip 0.0.0.0, dip 255.255.255.255, proto 17 hdr len 20
len 576, id 0, cksum 78ae, ttl 64, dscp 0
fragment ok, last fragment, frag off 0
#udp: sport 68 dport 67 len 556
#dhcp: message-type: request
hardware type: 1, len: 6, hops: 0
txn id: 0x67458b6b, seconds elapsed: 0
client mac: b8:27:eb:1d:32:36
magic cookie: 0x63825363
#dhcp-option: message-type: discover
[asap_firewall_forward(5883):vlan decision] len 590, vlan 20, egress CP, ingress aruba000:
[asap_firewall_check_dhcp_packet(2606):dhcp packet from client] len 590, vlan 20 , egress CP, ingress aruba000:
[asap_firewall_forward(6383):looking up pkt ingress/src bridge entry b8:27:eb:1d :32:36] len 590, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6431):Found ingress/src bridge entry b8:27:eb:1d:32:36 re chable via aruba000] len 590, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6728):bridge section, looking for dst bridge entry ff:ff: ff:ff:ff:ff] len 590, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6840):Unable to find dst bridge entry ff:ff:ff:ff:ff:ff, flood to VLAN 20] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(6875):session section] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7129):fastpath session returned 1 opcode 4] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7143):slowpath section: opcode 4] len 590, vlan 20, egres s vlan 20, ingress aruba000:
[asap_firewall_forward(7416):back to fastpath, opcode 3] len 590, vlan 20, egres s vlan 20, ingress aruba000:
[asap_firewall_forward(7742):route section] len 590, vlan 20, egress vlan 20, in gress aruba000:
[asap_firewall_forward(7797):cp route section] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(8128):forward section] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9646):flooding] len 590, vlan 20, egress vlan 20, ingress a ruba000:
[asap_firewall_flood(9859):checking dev2 bond0] len 590, vlan 20, egress vlan 20 , ingress aruba000:
[asap_firewall_flood(10405):flooding to bond0, tags 1] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev13 aruba000] len 590, vlan 20, egress vla n 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to aruba000, tags 0] len 590, vlan 20, egre ss vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev14 aruba100] len 590, vlan 20, egress vla n 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to aruba100, tags 0] len 590, vlan 20, egre ss vlan 20, ingress aruba000:
[asap_firewall_flood(10423):stack section protocol=0x800, type=1] len 590, vlan 20, egress vlan 20, ingress aruba000:

Received packet from aruba000 (timestamp (2018-7-11 07:13:10:119588))
[asap_firewall_forward(5700):firewall entry] len 590, vlan 0, egress CP, ingress aruba000:
#mac: etype 0800 smac b8:27:eb:1d:32:36 dmac ff:ff:ff:ff:ff:ff
#ip: sip 0.0.0.0, dip 255.255.255.255, proto 17 hdr len 20
len 576, id 0, cksum 78ae, ttl 64, dscp 0
fragment ok, last fragment, frag off 0
#udp: sport 68 dport 67 len 556
#dhcp: message-type: request
hardware type: 1, len: 6, hops: 0
txn id: 0x67458b6b, seconds elapsed: 0
client mac: b8:27:eb:1d:32:36
magic cookie: 0x63825363
#dhcp-option: message-type: discover
[asap_firewall_forward(5883):vlan decision] len 590, vlan 20, egress CP, ingress aruba000:
[asap_firewall_check_dhcp_packet(2606):dhcp packet from client] len 590, vlan 20 , egress CP, ingress aruba000:
[asap_firewall_forward(6383):looking up pkt ingress/src bridge entry b8:27:eb:1d :32:36] len 590, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6431):Found ingress/src bridge entry b8:27:eb:1d:32:36 re chable via aruba000] len 590, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6728):bridge section, looking for dst bridge entry ff:ff: ff:ff:ff:ff] len 590, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6840):Unable to find dst bridge entry ff:ff:ff:ff:ff:ff, flood to VLAN 20] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(6875):session section] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7129):fastpath session returned 1 opcode 4] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7143):slowpath section: opcode 4] len 590, vlan 20, egres s vlan 20, ingress aruba000:
[asap_firewall_forward(7416):back to fastpath, opcode 3] len 590, vlan 20, egres s vlan 20, ingress aruba000:
[asap_firewall_forward(7742):route section] len 590, vlan 20, egress vlan 20, in gress aruba000:
[asap_firewall_forward(7797):cp route section] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(8128):forward section] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9646):flooding] len 590, vlan 20, egress vlan 20, ingress a ruba000:
[asap_firewall_flood(9859):checking dev2 bond0] len 590, vlan 20, egress vlan 20 , ingress aruba000:
[asap_firewall_flood(10405):flooding to bond0, tags 1] len 590, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev13 aruba000] len 590, vlan 20, egress vla n 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to aruba000, tags 0] len 590, vlan 20, egre ss vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev14 aruba100] len 590, vlan 20, egress vla n 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to aruba100, tags 0] len 590, vlan 20, egre ss vlan 20, ingress aruba000:
[asap_firewall_flood(10423):stack section protocol=0x800, type=1] len 590, vlan 20, egress vlan 20, ingress aruba000:

##Raspbian DHCP Pass##
Received packet from aruba000 (timestamp (2018-7-11 07:54:51:862149))
[asap_firewall_forward(5700):firewall entry] len 382, vlan 0, egress CP, ingress aruba000:
#mac: etype 0800 smac b8:27:eb:1d:32:36 dmac ff:ff:ff:ff:ff:ff
#ip: sip 0.0.0.0, dip 255.255.255.255, proto 17 hdr len 20
len 368, id 43406, cksum cfef, ttl 64, dscp 0
fragment ok, last fragment, frag off 0
#udp: sport 68 dport 67 len 348
#dhcp: message-type: request
hardware type: 1, len: 6, hops: 0
txn id: 0xbaea2234, seconds elapsed: 12
client mac: b8:27:eb:1d:32:36
magic cookie: 0x63825363
#dhcp-option: message-type: discover
[asap_firewall_forward(5883):vlan decision] len 382, vlan 20, egress CP, ingress aruba000:
[asap_firewall_check_dhcp_packet(2606):dhcp packet from client] len 382, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6383):looking up pkt ingress/src bridge entry b8:27:eb:1d:32:36] len 382, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6431):Found ingress/src bridge entry b8:27:eb:1d:32:36 rechable via aruba000] len 382, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6728):bridge section, looking for dst bridge entry ff:ff:ff:ff:ff:ff] len 382, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6840):Unable to find dst bridge entry ff:ff:ff:ff:ff:ff, flood to VLAN 20] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(6875):session section] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7129):fastpath session returned 1 opcode 4] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7143):slowpath section: opcode 4] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7416):back to fastpath, opcode 3] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7742):route section] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7797):cp route section] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(8128):forward section] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9646):flooding] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev2 bond0] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to bond0, tags 1] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev13 aruba000] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to aruba000, tags 0] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev14 aruba100] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to aruba100, tags 0] len 382, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(10423):stack section protocol=0x800, type=1] len 382, vlan 20, egress vlan 20, ingress aruba000:

Received packet from bond0 (timestamp (2018-7-11 07:54:51:869669))
[asap_firewall_forward(5700):firewall entry] len 359, vlan 0, egress CP, ingress bond0:
#mac: etype 8100 smac 02:04:96:83:4a:6d dmac b8:27:eb:1d:32:36
#vlan 20, prio 0, etype 0800
#ip: sip 172.16.20.1, dip 172.16.20.77, proto 17 hdr len 20
len 341, id 0, cksum b929, ttl 64, dscp 0
dont fragment, last fragment, frag off 0
#udp: sport 67 dport 68 len 321
#dhcp: message-type: reply
hardware type: 1, len: 6, hops: 0
txn id: 0xbaea2234, seconds elapsed: 0
your ip: 172.16.20.77
next server ip: 10.10.7.16
relay agent ip: 172.16.20.1
client mac: b8:27:eb:1d:32:36
magic cookie: 0x63825363
#dhcp-option: netmask: 255.255.255.0
#dhcp-option: router: 172.16.20.1
#dhcp-option: dns-server: 10.10.7.16,10.10.7.17
#dhcp-option: dns-name: ggi.generalglass.com
#dhcp-option: message-type: offer
#dhcp-option: dhcp-server: 10.10.7.16
[asap_firewall_forward(5883):vlan decision] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_check_dhcp_packet(2673):dhcp packet to client] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_forward(6383):looking up pkt ingress/src bridge entry 02:04:96:83:4a:6d] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_forward(6431):Found ingress/src bridge entry 02:04:96:83:4a:6d rechable via bond0] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_forward(6728):bridge section, looking for dst bridge entry b8:27:eb:1d:32:36] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_forward(6875):session section] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7129):fastpath session returned 1 opcode 4] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7143):slowpath section: opcode 4] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7416):back to fastpath, opcode 3] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7742):route section] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7797):cp route section] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(8128):forward section] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(8523):forwarding packet to aruba000] len 355, vlan 20, egress aruba000, ingress aruba000:
#mac: etype 0800 smac 02:04:96:83:4a:6d dmac b8:27:eb:1d:32:36
#ip: sip 172.16.20.1, dip 172.16.20.77, proto 17 hdr len 20
len 341, id 0, cksum b929, ttl 64, dscp 0
dont fragment, last fragment, frag off 0
#udp: sport 67 dport 68 len 321
#dhcp: message-type: reply
hardware type: 1, len: 6, hops: 0
txn id: 0xbaea2234, seconds elapsed: 0
your ip: 172.16.20.77
next server ip: 10.10.7.16
relay agent ip: 172.16.20.1
client mac: b8:27:eb:1d:32:36
magic cookie: 0x63825363
#dhcp-option: netmask: 255.255.255.0
#dhcp-option: router: 172.16.20.1
#dhcp-option: dns-server: 10.10.7.16,10.10.7.17
#dhcp-option: dns-name: ggi.generalglass.com
#dhcp-option: message-type: offer
#dhcp-option: dhcp-server: 10.10.7.16

Received packet from aruba000 (timestamp (2018-7-11 07:54:51:873015))
[asap_firewall_forward(5700):firewall entry] len 392, vlan 0, egress CP, ingress aruba000:
#mac: etype 0800 smac b8:27:eb:1d:32:36 dmac ff:ff:ff:ff:ff:ff
#ip: sip 0.0.0.0, dip 255.255.255.255, proto 17 hdr len 20
len 378, id 29258, cksum 72a, ttl 64, dscp 0
fragment ok, last fragment, frag off 0
#udp: sport 68 dport 67 len 358
#dhcp: message-type: request
hardware type: 1, len: 6, hops: 0
txn id: 0xbaea2234, seconds elapsed: 12
client mac: b8:27:eb:1d:32:36
magic cookie: 0x63825363
#dhcp-option: requested-ip: 172.16.20.77
#dhcp-option: message-type: request
#dhcp-option: dhcp-server: 10.10.7.16
[asap_firewall_forward(5883):vlan decision] len 392, vlan 20, egress CP, ingress aruba000:
[asap_firewall_check_dhcp_packet(2606):dhcp packet from client] len 392, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6383):looking up pkt ingress/src bridge entry b8:27:eb:1d:32:36] len 392, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6431):Found ingress/src bridge entry b8:27:eb:1d:32:36 rechable via aruba000] len 392, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6728):bridge section, looking for dst bridge entry ff:ff:ff:ff:ff:ff] len 392, vlan 20, egress CP, ingress aruba000:
[asap_firewall_forward(6840):Unable to find dst bridge entry ff:ff:ff:ff:ff:ff, flood to VLAN 20] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(6875):session section] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7129):fastpath session returned 1 opcode 4] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7143):slowpath section: opcode 4] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7416):back to fastpath, opcode 3] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7742):route section] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(7797):cp route section] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_forward(8128):forward section] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9646):flooding] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev2 bond0] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to bond0, tags 1] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev13 aruba000] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to aruba000, tags 0] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(9859):checking dev14 aruba100] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(10405):flooding to aruba100, tags 0] len 392, vlan 20, egress vlan 20, ingress aruba000:
[asap_firewall_flood(10423):stack section protocol=0x800, type=1] len 392, vlan 20, egress vlan 20, ingress aruba000:

Received packet from bond0 (timestamp (2018-7-11 07:54:51:881131))
[asap_firewall_forward(5700):firewall entry] len 359, vlan 0, egress CP, ingress bond0:
#mac: etype 8100 smac 02:04:96:83:4a:6d dmac b8:27:eb:1d:32:36
#vlan 20, prio 0, etype 0800
#ip: sip 172.16.20.1, dip 172.16.20.77, proto 17 hdr len 20
len 341, id 0, cksum b929, ttl 64, dscp 0
dont fragment, last fragment, frag off 0
#udp: sport 67 dport 68 len 321
#dhcp: message-type: reply
hardware type: 1, len: 6, hops: 0
txn id: 0xbaea2234, seconds elapsed: 0
your ip: 172.16.20.77
relay agent ip: 172.16.20.1
client mac: b8:27:eb:1d:32:36
magic cookie: 0x63825363
#dhcp-option: netmask: 255.255.255.0
#dhcp-option: router: 172.16.20.1
#dhcp-option: dns-server: 10.10.7.16,10.10.7.17
#dhcp-option: dns-name: ggi.generalglass.com
#dhcp-option: message-type: ack
#dhcp-option: dhcp-server: 10.10.7.16
[asap_firewall_forward(5883):vlan decision] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_check_dhcp_packet(2673):dhcp packet to client] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_forward(6383):looking up pkt ingress/src bridge entry 02:04:96:83:4a:6d] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_forward(6431):Found ingress/src bridge entry 02:04:96:83:4a:6d rechable via bond0] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_forward(6728):bridge section, looking for dst bridge entry b8:27:eb:1d:32:36] len 359, vlan 20, egress CP, ingress bond0:
[asap_firewall_forward(6875):session section] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7129):fastpath session returned 1 opcode 4] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7143):slowpath section: opcode 4] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7416):back to fastpath, opcode 3] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7742):route section] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(7797):cp route section] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(8128):forward section] len 359, vlan 20, egress aruba000, ingress bond0:
[asap_firewall_forward(8523):forwarding packet to aruba000] len 355, vlan 20, egress aruba000, ingress aruba000:
#mac: etype 0800 smac 02:04:96:83:4a:6d dmac b8:27:eb:1d:32:36
#ip: sip 172.16.20.1, dip 172.16.20.77, proto 17 hdr len 20
len 341, id 0, cksum b929, ttl 64, dscp 0
dont fragment, last fragment, frag off 0
#udp: sport 67 dport 68 len 321
#dhcp: message-type: reply
hardware type: 1, len: 6, hops: 0
txn id: 0xbaea2234, seconds elapsed: 0
your ip: 172.16.20.77
relay agent ip: 172.16.20.1
client mac: b8:27:eb:1d:32:36
magic cookie: 0x63825363
#dhcp-option: netmask: 255.255.255.0
#dhcp-option: router: 172.16.20.1
#dhcp-option: dns-server: 10.10.7.16,10.10.7.17
#dhcp-option: dns-name: ggi.generalglass.com
#dhcp-option: message-type: ack
#dhcp-option: dhcp-server: 10.10.7.16

RonM
Posts: 6
Joined: Thu Jul 05, 2018 11:18 pm

Re: Wifi DHCP failure

Post by RonM » Mon Jul 16, 2018 3:26 pm

I figured this out for anyone having this issue.

Several months ago I moved from a single DHCP server to a Windows fail-over DHCP server. The Microsoft documentation in this instance states that no DHCP Helper settings need to be changed network wise. The DHCP service would forward the request to the appropriate server regardless of which was contacted. Well, yeah this is true apparently for Windows and most other OS's. However some, such as WTware do not play well. I had to add the second DHCP server into my Extreme Summit switch config. Once that was done, WTware started working with DHCP properly again. Incidentally, this helped me with my PXE boot issue as well. That needed to be added as a DHCP helper as well.

akatik
SUPPORT
SUPPORT
Posts: 277
Joined: Mon Jan 17, 2005 6:30 pm
Contact:

Re: Wifi DHCP failure

Post by akatik » Tue Jul 17, 2018 1:41 pm

RonM wrote:
Mon Jul 16, 2018 3:26 pm
...Well, yeah this is true apparently for Windows and most other OS's. However some, such as WTware do not play well. I had to add the second DHCP server into my Extreme Summit switch config. Once that was done, WTware started working with DHCP properly again.
Could you return it back and make me new log, please?

I added more debugging: http://wtware.com/testing/201807171000.zip

RonM
Posts: 6
Joined: Thu Jul 05, 2018 11:18 pm

Re: Wifi DHCP failure

Post by RonM » Fri Jul 20, 2018 6:45 pm

unfortunately I reverted my changes and I am unable to reproduce now. When I get a new batch of pi's in I will try again.

Post Reply