Reboot causes router to crash and reboot

Status
Not open for further replies.

JamesWorts

Dabbler
Joined
Apr 29, 2017
Messages
17
Hi All,

I am having an ongoing issue,

Originally I had an Asus router and kept getting weird DSL connection drops, so replaced it with my original Plusnet hub one.

Every time I have to reboot FreeNAS, the router will crash and reboot.

Anyone know why this is happening? I have a suspicion that my issues with the Asus router and the Plusnet router are linked to my FreeNAS build.

I have included router and freenas logs, if these are of no use or incorrect, please let me know.

Thanks all.

Router Log:- problem occurred at around 20:00
Code:
20:17:12, 01 Sep. ( 1036.550000) CWMP: Session start now. Event code(s): '0 BOOTSTRAP,4 VALUE CHANGE'
20:11:50, 01 Sep. ath00: STA f0:99:bf:82:81:ba IEEE 802.11: Client disassociated
20:11:50, 01 Sep. ath00: STA f0:99:bf:82:81:ba IEEE 802.11: WiFi registration failed
20:11:50, 01 Sep. ath10: STA 60:92:17:7b:dd:68 IEEE 802.11: WiFi registration failed
20:11:16, 01 Sep. ath10: STA 28:ed:6a:b9:48:42 IEEE 802.11: Client associated
20:10:48, 01 Sep. ath10: STA 28:ed:6a:b9:48:42 IEEE 802.11: Client disassociated
20:10:45, 01 Sep. ath10: STA 28:ed:6a:b9:48:42 IEEE 802.11: Client associated
20:08:03, 01 Sep. ath10: STA 60:92:17:7b:dd:68 IEEE 802.11: Client associated
20:05:06, 01 Sep. ath10: STA d0:4f:7e:bf:7d:12 IEEE 802.11: Client associated
20:05:06, 01 Sep. ath00: STA d0:4f:7e:bf:7d:12 IEEE 802.11: Client disassociated
20:03:42, 01 Sep. ( 226.210000) Wire Lan Port 4 up
20:03:38, 01 Sep. ( 221.950000) Wire Lan Port 4 down
20:03:30, 01 Sep. ( 214.450000) Wire Lan Port 4 up
20:03:26, 01 Sep. ( 210.500000) Wire Lan Port 4 down
20:03:23, 01 Sep. ( 207.040000) Wire Lan Port 4 up
20:03:19, 01 Sep. ( 203.760000) Wire Lan Port 4 down
20:03:10, 01 Sep. ath00: STA 5c:cf:7f:63:05:74 IEEE 802.11: Client associated
20:03:02, 01 Sep. ath00: STA 5c:cf:7f:63:05:74 IEEE 802.11: Client disassociated
20:02:54, 01 Sep. ath00: STA 5c:cf:7f:63:05:74 IEEE 802.11: Client associated
20:02:51, 01 Sep. ath00: STA 5c:cf:7f:63:05:74 IEEE 802.11: Client disassociated
20:02:29, 01 Sep. ath00: STA 5c:cf:7f:63:05:74 IEEE 802.11: Client associated
20:02:27, 01 Sep. ath10: STA fc:a1:83:cb:be:09 IEEE 802.11: Client associated
20:02:14, 01 Sep. ath10: STA 60:92:17:7b:dd:68 IEEE 802.11: Client disassociated
20:02:12, 01 Sep. ( 136.140000) CWMP: session closed due to error: Could not resolve host
20:02:12, 01 Sep. ath10: STA 40:98:ad:a0:c2:d0 IEEE 802.11: Client associated
20:02:11, 01 Sep. ( 134.980000) CWMP: Server URL: https://ceased.tr69.p; Connecting as user: ACS username
20:02:11, 01 Sep. ( 134.970000) CWMP: Session start now. Event code(s): '0 BOOTSTRAP,4 VALUE CHANGE'
20:02:11, 01 Sep. ath10: STA 28:ed:6a:b9:48:42 IEEE 802.11: Client disassociated
20:02:05, 01 Sep. ath10: STA 28:ed:6a:b9:48:42 IEEE 802.11: Client associated
20:02:05, 01 Sep. ath00: STA 60:01:94:99:81:c8 IEEE 802.11: Client associated
20:02:03, 01 Sep. ath00: STA 60:01:94:99:81:c8 IEEE 802.11: Client disassociated
20:01:47, 01 Sep. ath00: STA 00:d0:2d:e8:06:25 IEEE 802.11: Client associated
20:01:46, 01 Sep. ath00: STA 00:d0:2d:e8:06:25 IEEE 802.11: Client disassociated
20:01:39, 01 Sep. ( 103.340000) CWMP: session closed due to error: Could not resolve host
20:01:38, 01 Sep. ( 101.910000) NTP synchronization success!
20:01:34, 01 Sep. ath10: STA fc:65:de:fc:41:ae IEEE 802.11: Client associated
20:01:31, 01 Sep. ath10: STA fc:65:de:fc:41:ae IEEE 802.11: Client disassociated
20:01:27, 01 Sep. ( 91.310000) CWMP: Server URL: https://ceased.tr69.p; Connecting as user: ACS username
20:01:27, 01 Sep. ( 91.300000) CWMP: Session start now. Event code(s): '0 BOOTSTRAP,4 VALUE CHANGE'
20:01:26, 01 Sep. ( 90.490000) NTP synchronization start
20:01:25, 01 Sep. ( 89.120000) WAN operating mode is VDSL
20:01:25, 01 Sep. ( 89.120000) Last WAN operating mode was VDSL
20:01:24, 01 Sep. ( 87.570000) PPP IPCP Receive Configuration ACK
20:01:23, 01 Sep. ( 87.540000) PPP IPCP Send Configuration ACK
20:01:23, 01 Sep. ( 87.530000) PPP IPCP Receive Configuration Request
20:01:23, 01 Sep. ( 86.900000) PPP IPCP Send Configuration Request
20:01:23, 01 Sep. ( 86.890000) PPP IPCP Receive Configuration NAK
20:01:22, 01 Sep. ( 86.480000) PPP IPCP Send Configuration Request
20:01:21, 01 Sep. ( 85.250000) PPPoE is up - Down Rate=42233Kbps, Up Rate=5347Kbps; SNR Margin Down=1.1dB, Up=6.1dB
20:01:21, 01 Sep. ( 85.230000) CHAP authentication successful
20:01:21, 01 Sep. ( 84.580000) CHAP Receive Challenge
20:01:21, 01 Sep. ( 84.580000) Starting CHAP authentication with peer
20:01:21, 01 Sep. ( 84.580000) PPP LCP Receive Configuration ACK
20:01:21, 01 Sep. ( 84.560000) PPP LCP Send Configuration ACK
20:01:21, 01 Sep. ( 84.550000) PPP LCP Send Configuration Request
20:01:21, 01 Sep. ( 84.550000) PPP LCP Receive Configuration Request
20:01:20, 01 Sep. ( 84.470000) CHAP Receive Challenge
20:01:20, 01 Sep. ( 84.450000) Starting CHAP authentication with peer
20:01:20, 01 Sep. ( 84.450000) PPP LCP Receive Configuration ACK
20:01:20, 01 Sep. ( 84.430000) PPP LCP Send Configuration Request
20:01:20, 01 Sep. ( 84.430000) PPP LCP Receive Configuration Reject
20:01:20, 01 Sep. ( 84.420000) PPP LCP Send Configuration ACK
20:01:20, 01 Sep. ( 84.420000) PPP LCP Receive Configuration Request
20:01:20, 01 Sep. ( 84.400000) PPP LCP Send Configuration Request
20:01:17, 01 Sep. ( 80.740000) CWMP: session closed due to error: Could not resolve host
20:01:14, 01 Sep. ( 78.450000) PTM over DSL is up
20:01:09, 01 Sep. ( 73.370000) CWMP: Server URL: https://ceased.tr69.p; Connecting as user: ACS username
20:01:09, 01 Sep. ( 73.360000) CWMP: Session start now. Event code(s): '0 BOOTSTRAP,4 VALUE CHANGE'
20:01:08, 01 Sep. ath10: STA 40:98:ad:a0:c2:d0 IEEE 802.11: Client disassociated
20:01:05, 01 Sep. ath00: STA d0:4f:7e:bf:7d:12 IEEE 802.11: Client associated
20:01:02, 01 Sep. ath10: STA fc:65:de:fc:41:ae IEEE 802.11: Client associated
20:00:59, 01 Sep. ath10: STA 2c:54:91:59:50:b5 IEEE 802.11: Client associated
20:00:59, 01 Sep. ath00: STA 9a:aa:fc:30:da:29 IEEE 802.11: Client associated
20:00:57, 01 Sep. ath00: STA 3c:07:71:f1:59:16 IEEE 802.11: Client associated
20:00:56, 01 Sep. ath10: STA 40:98:ad:a0:c2:d0 IEEE 802.11: Client associated
20:00:56, 01 Sep. ath00: STA 9a:aa:fc:30:da:29 IEEE 802.11: Client disassociated
20:00:54, 01 Sep. ath00: STA d0:4f:7e:bf:7d:12 IEEE 802.11: Client disassociated
20:00:54, 01 Sep. ath00: STA a4:5d:36:d5:1d:20 IEEE 802.11: Client associated
20:00:54, 01 Sep. ath00: STA 9a:aa:fc:30:da:29 IEEE 802.11: Client associated
20:00:53, 01 Sep. ath00: STA 9a:aa:fc:30:da:29 IEEE 802.11: Client disassociated
20:00:50, 01 Sep. ath00: STA 60:01:94:99:81:c8 IEEE 802.11: Client associated
20:00:47, 01 Sep. ath10: STA c0:97:27:5e:6e:a4 IEEE 802.11: Client associated
20:00:47, 01 Sep. ath00: STA a0:f3:c1:ed:2d:fe IEEE 802.11: Client associated
20:00:47, 01 Sep. ath00: STA f0:99:bf:82:81:ba IEEE 802.11: Client associated
20:00:47, 01 Sep. ath00: STA d0:4f:7e:bf:7d:12 IEEE 802.11: Client associated
20:00:46, 01 Sep. ath00: STA d0:4f:7e:bf:7d:12 IEEE 802.11: Client disassociated
20:00:44, 01 Sep. ath00: STA d0:4f:7e:bf:7d:12 IEEE 802.11: Client associated
20:00:44, 01 Sep. ath00: STA 9a:aa:fc:30:da:29 IEEE 802.11: Client associated
20:00:43, 01 Sep. ath10: STA b8:17:c2:bb:dc:b6 IEEE 802.11: Client associated
20:00:43, 01 Sep. ath00: STA 00:d0:2d:e8:06:25 IEEE 802.11: Client associated
20:00:40, 01 Sep. ath00: STA 00:d0:2d:e8:06:25 IEEE 802.11: Client disassociated
20:00:39, 01 Sep. ( 42.850000) CWMP: session closed due to error: Could not resolve host
20:00:39, 01 Sep. ath10: STA 60:92:17:7b:dd:68 IEEE 802.11: Client associated
20:00:39, 01 Sep. ath10: STA f0:81:73:e9:2a:4a IEEE 802.11: Client associated
20:00:36, 01 Sep. ath00: STA 50:dc:e7:59:a9:b4 IEEE 802.11: Client associated
20:00:33, 01 Sep. ( 36.870000) CWMP: Server URL: https://ceased.tr69.p; Connecting as user: ACS username
20:00:33, 01 Sep. ( 36.860000) CWMP: Session start now. Event code(s): '0 BOOTSTRAP,4 VALUE CHANGE'
20:00:32, 01 Sep. ( 36.480000) CWMP: Initializing transaction for event code 0 BOOTSTRAP
20:00:31, 01 Sep. ( 35.140000) WiFi auto selected channel 48
20:00:31, 01 Sep. ( 35.140000) 36-94::40-94::44-94::48-93::52-92::56-92::60-92::64-92::100-95::104-96::108-97::112-97::116-97::120-97::124-98::128-99
20:00:31, 01 Sep. ( 35.130000) 5 GHz Wireless: Rescan, Reason: 'Power-up'
20:00:31, 01 Sep. ( 35.130000) WiFi auto selected channel 1
20:00:31, 01 Sep. ( 35.130000) 1-90::2-101::3-91::4-91::5-91::6-92::7-93::8-93::9-94::10-95::11-95::12-95::13-95
20:00:31, 01 Sep. ( 35.130000) 2.4 GHz Wireless: Rescan, Reason: 'Power-up'
20:00:31, 01 Sep. ( 35.130000) Wire Lan Port 4 up
20:00:31, 01 Sep. ( 35.130000) Wire Lan Port 3 up
20:00:31, 01 Sep. ( 35.130000) Wire Lan Port 2 up
20:00:31, 01 Sep. ( 35.120000) Wire Lan Port 1 up
20:00:31, 01 Sep. ( 34.640000) WAN Auto-sensing running.
20:00:27, 01 Sep. ( 30.580000) System up, firmware version: 4.7.5.1.83.8.237.2.2
20:00:22, 01 Sep. ( 25.750000) WPA2 mode selected
20:00:22, 01 Sep. ( 25.750000) WPS enabled
20:00:20, 01 Sep. ( 23.870000) WPA2 mode selected
20:00:20, 01 Sep. ( 23.870000) WPS enabled
20:00:11, 01 Sep. ( 15.370000) System start 
19:18:20, 01 Sep. ath10: STA 70:e7:2c:8a:0b:fa IEEE 802.11: Client disassociated
19:16:13, 01 Sep. ath10: STA 70:e7:2c:8a:0b:fa IEEE 802.11: Client associated
19:06:27, 01 Sep. ath10: STA 40:98:ad:a0:c2:d0 IEEE 802.11: Client associated
17:42:32, 01 Sep. ath10: STA 40:98:ad:a0:c2:d0 IEEE 802.11: WiFi registration failed
17:22:48, 01 Sep. ath10: STA 40:98:ad:a0:c2:d0 IEEE 802.11: Client associated
17:22:32, 01 Sep. ath10: STA 40:98:ad:a0:c2:d0 IEEE 802.11: WiFi registration failed
17:12:39, 01 Sep. (531838.690000) New GUI session from IP 192.168.0.78
17:05:47, 01 Sep. ath10: STA 40:98:ad:a0:c2:d0 IEEE 802.11: Client associated



FreeNAS
Code:
Sep  1 19:49:58 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 19:58:21 freenas syslog-ng[1631]: syslog-ng starting up; version='3.7.3'
Sep  1 19:58:21 freenas Copyright (c) 1992-2017 The FreeBSD Project.
Sep  1 19:58:21 freenas Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
Sep  1 19:58:21 freenas	 The Regents of the University of California. All rights reserved.
Sep  1 19:58:21 freenas FreeBSD is a registered trademark of The FreeBSD Foundation.
Sep  1 19:58:21 freenas FreeBSD 11.1-STABLE #0 r321665+9902d126c39(freenas/11.1-stable): Tue Aug 21 12:24:37 EDT 2018
Sep  1 19:58:21 freenas root@nemesis.tn.ixsystems.com:/freenas-11-releng/freenas/_BE/objs/freenas-11-releng/freenas/_BE/os/sys/FreeNAS.amd64 amd64
Sep  1 19:58:21 freenas FreeBSD clang version 5.0.0 (tags/RELEASE_500/final 312559) (based on LLVM 5.0.0svn)
Sep  1 19:58:21 freenas VT(efifb): resolution 800x600
Sep  1 19:58:21 freenas CPU: Intel(R) Core(TM) i5-2400 CPU @ 3.10GHz (3093.04-MHz K8-class CPU)
Sep  1 19:58:21 freenas Origin="GenuineIntel"  Id=0x206a7  Family=0x6  Model=0x2a  Stepping=7
Sep  1 19:58:21 freenas Features=0xbfebfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
Sep  1 19:58:21 freenas Features2=0x1fbae3ff<SSE3,PCLMULQDQ,DTES64,MON,DS_CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,TSCDLT,AESNI,XSAVE,OSXSAVE,AVX>
Sep  1 19:58:21 freenas AMD Features=0x28100800<SYSCALL,NX,RDTSCP,LM>
Sep  1 19:58:21 freenas AMD Features2=0x1<LAHF>
Sep  1 19:58:21 freenas XSAVE Features=0x1<XSAVEOPT>
Sep  1 19:58:21 freenas VT-x: PAT,HLT,MTF,PAUSE,EPT,UG,VPID
Sep  1 19:58:21 freenas TSC: P-state invariant, performance statistics
Sep  1 19:58:21 freenas real memory  = 17951621120 (17120 MB)
Sep  1 19:58:21 freenas avail memory = 16454586368 (15692 MB)
Sep  1 19:58:21 freenas Event timer "LAPIC" quality 600
Sep  1 19:58:21 freenas ACPI APIC Table: <DELL   CBX3   >
Sep  1 19:58:21 freenas FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs
Sep  1 19:58:21 freenas FreeBSD/SMP: 1 package(s) x 4 core(s)
Sep  1 19:58:21 freenas WARNING: VIMAGE (virtualized network stack) is a highly experimental feature.
Sep  1 19:58:21 freenas ioapic0 <Version 2.0> irqs 0-23 on motherboard
Sep  1 19:58:21 freenas SMP: AP CPU #1 Launched!
Sep  1 19:58:21 freenas SMP: AP CPU #3 Launched!
Sep  1 19:58:21 freenas SMP: AP CPU #2 Launched!
Sep  1 19:58:21 freenas Timecounter "TSC-low" frequency 1546518910 Hz quality 1000
Sep  1 19:58:21 freenas random: entropy device external interface
Sep  1 19:58:21 freenas kbd1 at kbdmux0
Sep  1 19:58:21 freenas module_register_init: MOD_LOAD (vesa, 0xffffffff80fc84c0, 0) error 19
Sep  1 19:58:21 freenas nexus0
Sep  1 19:58:21 freenas cryptosoft0: <software crypto> on motherboard
Sep  1 19:58:21 freenas aesni0: <AES-CBC,AES-XTS,AES-GCM,AES-ICM> on motherboard
Sep  1 19:58:21 freenas padlock0: No ACE support.
Sep  1 19:58:21 freenas acpi0: <DELL CBX3   > on motherboard
Sep  1 19:58:21 freenas acpi0: Power Button (fixed)
Sep  1 19:58:21 freenas cpu0: <ACPI CPU> on acpi0
Sep  1 19:58:21 freenas cpu1: <ACPI CPU> on acpi0
Sep  1 19:58:21 freenas cpu2: <ACPI CPU> on acpi0
Sep  1 19:58:21 freenas cpu3: <ACPI CPU> on acpi0
Sep  1 19:58:21 freenas hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
Sep  1 19:58:21 freenas Timecounter "HPET" frequency 14318180 Hz quality 950
Sep  1 19:58:21 freenas Event timer "HPET" frequency 14318180 Hz quality 550
Sep  1 19:58:21 freenas Event timer "HPET1" frequency 14318180 Hz quality 440
Sep  1 19:58:21 freenas Event timer "HPET2" frequency 14318180 Hz quality 440
Sep  1 19:58:21 freenas Event timer "HPET3" frequency 14318180 Hz quality 440
Sep  1 19:58:21 freenas Event timer "HPET4" frequency 14318180 Hz quality 440
Sep  1 19:58:21 freenas atrtc0: <AT realtime clock> port 0x70-0x77 irq 8 on acpi0
Sep  1 19:58:21 freenas atrtc0: Warning: Couldn't map I/O.
Sep  1 19:58:21 freenas atrtc0: registered as a time-of-day clock, resolution 1.000000s
Sep  1 19:58:21 freenas Event timer "RTC" frequency 32768 Hz quality 0
Sep  1 19:58:21 freenas attimer0: <AT timer> port 0x40-0x43,0x50-0x53 irq 0 on acpi0
Sep  1 19:58:21 freenas Timecounter "i8254" frequency 1193182 Hz quality 0
Sep  1 19:58:21 freenas Event timer "i8254" frequency 1193182 Hz quality 100
Sep  1 19:58:21 freenas Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
Sep  1 19:58:21 freenas acpi_timer0: <24-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0
Sep  1 19:58:21 freenas pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
Sep  1 19:58:21 freenas pcib0: _OSC returned error 0x4
Sep  1 19:58:21 freenas pci0: <ACPI PCI bus> on pcib0
Sep  1 19:58:21 freenas vgapci0: <VGA-compatible display> port 0x3000-0x303f mem 0xe0c00000-0xe0ffffff,0xd0000000-0xdfffffff irq 16 at device 2.0 on pci0
Sep  1 19:58:21 freenas vgapci0: Boot video device
Sep  1 19:58:21 freenas pci0: <simple comms> at device 22.0 (no driver attached)
Sep  1 19:58:21 freenas em0: <Intel(R) PRO/1000 Network Connection 7.6.1-k> port 0x3080-0x309f mem 0xe1a00000-0xe1a1ffff,0xe1a70000-0xe1a70fff irq 20 at device 25.0 on pci0
Sep  1 19:58:21 freenas em0: Using an MSI interrupt
Sep  1 19:58:21 freenas em0: Ethernet address: 18:03:73:29:86:f3
Sep  1 19:58:21 freenas ehci0: <Intel Cougar Point USB 2.0 controller> mem 0xe1a60000-0xe1a603ff irq 16 at device 26.0 on pci0
Sep  1 19:58:21 freenas usbus0: EHCI version 1.0
Sep  1 19:58:21 freenas usbus0 on ehci0
Sep  1 19:58:21 freenas usbus0: 480Mbps High Speed USB v2.0
Sep  1 19:58:21 freenas pcib1: <ACPI PCI-PCI bridge> irq 16 at device 28.0 on pci0
Sep  1 19:58:21 freenas pci1: <ACPI PCI bus> on pcib1
Sep  1 19:58:21 freenas pcib2: <ACPI PCI-PCI bridge> irq 18 at device 28.2 on pci0
Sep  1 19:58:21 freenas pcib2: [GIANT-LOCKED]
Sep  1 19:58:21 freenas ehci1: <Intel Cougar Point USB 2.0 controller> mem 0xe1a50000-0xe1a503ff irq 17 at device 29.0 on pci0
Sep  1 19:58:21 freenas usbus1: EHCI version 1.0
Sep  1 19:58:21 freenas usbus1 on ehci1
Sep  1 19:58:21 freenas usbus1: 480Mbps High Speed USB v2.0
Sep  1 19:58:21 freenas pcib3: <ACPI PCI-PCI bridge> at device 30.0 on pci0
Sep  1 19:58:21 freenas pci2: <ACPI PCI bus> on pcib3
Sep  1 19:58:21 freenas isab0: <PCI-ISA bridge> at device 31.0 on pci0
Sep  1 19:58:21 freenas isa0: <ISA bus> on isab0
Sep  1 19:58:21 freenas ahci0: <Intel Cougar Point AHCI SATA controller> port 0x30d0-0x30d7,0x30c0-0x30c3,0x30b0-0x30b7,0x30a0-0x30a3,0x3060-0x307f mem 0xe1a40000-0xe1a407ff irq 18 at device 31.2 on pci0
Sep  1 19:58:21 freenas ahci0: AHCI v1.30 with 6 6Gbps ports, Port Multiplier not supported
Sep  1 19:58:21 freenas ahcich0: <AHCI channel> at channel 0 on ahci0
Sep  1 19:58:21 freenas acpi_button0: <Power Button> on acpi0
Sep  1 19:58:21 freenas acpi_syscontainer0: <System Container> on acpi0
Sep  1 19:58:21 freenas ichwd0: <Intel Cougar Point watchdog timer> on isa0
Sep  1 19:58:21 freenas atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
Sep  1 19:58:21 freenas atkbd0: <AT Keyboard> irq 1 on atkbdc0
Sep  1 19:58:21 freenas kbd0 at atkbd0
Sep  1 19:58:21 freenas atkbd0: [GIANT-LOCKED]
Sep  1 19:58:21 freenas uart0: <Non-standard ns8250 class UART with FIFOs> at port 0x3f8 irq 4 flags 0x10 on isa0
Sep  1 19:58:21 freenas coretemp0: <CPU On-Die Thermal Sensors> on cpu0
Sep  1 19:58:21 freenas est0: <Enhanced SpeedStep Frequency Control> on cpu0
Sep  1 19:58:21 freenas coretemp1: <CPU On-Die Thermal Sensors> on cpu1
Sep  1 19:58:21 freenas est1: <Enhanced SpeedStep Frequency Control> on cpu1
Sep  1 19:58:21 freenas coretemp2: <CPU On-Die Thermal Sensors> on cpu2
Sep  1 19:58:21 freenas est2: <Enhanced SpeedStep Frequency Control> on cpu2
Sep  1 19:58:21 freenas coretemp3: <CPU On-Die Thermal Sensors> on cpu3
Sep  1 19:58:21 freenas est3: <Enhanced SpeedStep Frequency Control> on cpu3
Sep  1 19:58:21 freenas ZFS filesystem version: 5
Sep  1 19:58:21 freenas ZFS storage pool version: features support (5000)
Sep  1 19:58:21 freenas Timecounters tick every 1.000 msec
Sep  1 19:58:21 freenas freenas_sysctl: adding account.
Sep  1 19:58:21 freenas freenas_sysctl: adding directoryservice.
Sep  1 19:58:21 freenas freenas_sysctl: adding middlewared.
Sep  1 19:58:21 freenas freenas_sysctl: adding network.
Sep  1 19:58:21 freenas freenas_sysctl: adding services.
Sep  1 19:58:21 freenas ipfw2 (+ipv6) initialized, divert enabled, nat enabled, default to accept, logging disabled
Sep  1 19:58:21 freenas ugen0.1: <Intel EHCI root HUB> at usbus0
Sep  1 19:58:21 freenas ugen1.1: <Intel EHCI root HUB> at usbus1
Sep  1 19:58:21 freenas uhub0: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus0
Sep  1 19:58:21 freenas uhub1: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus1
Sep  1 19:58:21 freenas uhub0: 2 ports with 2 removable, self powered
Sep  1 19:58:21 freenas uhub1: 2 ports with 2 removable, self powered
Sep  1 19:58:21 freenas ugen0.2: <vendor 0x8087 product 0x0024> at usbus0
Sep  1 19:58:21 freenas uhub2 on uhub0
Sep  1 19:58:21 freenas uhub2: <vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, addr 2> on usbus0
Sep  1 19:58:21 freenas ugen1.2: <vendor 0x8087 product 0x0024> at usbus1
Sep  1 19:58:21 freenas uhub3 on uhub1
Sep  1 19:58:21 freenas uhub3: <vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, addr 2> on usbus1
Sep  1 19:58:21 freenas uhub2: 6 ports with 6 removable, self powered
Sep  1 19:58:21 freenas uhub3: 8 ports with 8 removable, self powered
Sep  1 19:58:21 freenas ugen1.3: <SanDisk Ultra Fit> at usbus1
Sep  1 19:58:21 freenas umass0 on uhub3
Sep  1 19:58:21 freenas umass0: <SanDisk Ultra Fit, class 0/0, rev 2.10/1.00, addr 3> on usbus1
Sep  1 19:58:21 freenas umass0:  SCSI over Bulk-Only; quirks = 0x8100
Sep  1 19:58:21 freenas umass0:2:0: Attached to scbus2
Sep  1 19:58:21 freenas ada0 at ahcich0 bus 0 scbus0 target 0 lun 0
Sep  1 19:58:21 freenas ada0: <TOSHIBA HDWQ140 FJ1M> ATA8-ACS SATA 3.x device
Sep  1 19:58:21 freenas ada0: Serial Number 17EUK00OFPBE
Sep  1 19:58:21 freenas ada0: 600.000MB/s transfersda0 at umass-sim0 bus 0 scbus2 target 0 lun 0
Sep  1 19:58:21 freenas (SATA 3.x, UDMA5, PIO 8192bytes)
Sep  1 19:58:21 freenas ada0: Command Queueing enabled
Sep  1 19:58:21 freenas da0: <SanDisk Ultra Fit 1.00> Removable Direct Access SPC-4 SCSI device
Sep  1 19:58:21 freenas da0: Serial Number 4C530001280227120275
Sep  1 19:58:21 freenas da0: 40.000MB/s transfersada0: 3815447MB (7814037168 512 byte sectors)
Sep  1 19:58:21 freenas da0: 14663MB (30031250 512 byte sectors)
Sep  1 19:58:21 freenas da0: quirks=0x2<NO_6_BYTE>
Sep  1 19:58:21 freenas GEOM: da0: the secondary GPT table is corrupt or invalid.
Sep  1 19:58:21 freenas GEOM: da0: using the primary only -- recovery suggested.
Sep  1 19:58:21 freenas random: unblocking device.
Sep  1 19:58:21 freenas Trying to mount root from zfs:freenas-boot/ROOT/11.1-U6 []...
Sep  1 19:58:21 freenas kernel: em0: link state changed to UP
Sep  1 19:58:21 freenas kernel: em0: link state changed to UP
Sep  1 19:58:21 freenas GEOM_RAID5: Module loaded, version 1.3.20140711.62 (rev f91e28e40bf7)
Sep  1 19:58:21 freenas GEOM_ELI: Device ada0p1.eli created.
Sep  1 19:58:21 freenas GEOM_ELI: Encryption: AES-XTS 128
Sep  1 19:58:21 freenas GEOM_ELI:	 Crypto: hardware
Sep  1 19:58:21 freenas hwpmc: SOFT/16/64/0x67<INT,USR,SYS,REA,WRI> TSC/1/64/0x20<REA> IAP/8/48/0x3ff<INT,USR,SYS,EDG,THR,REA,WRI,INV,QUA,PRC> IAF/3/48/0x67<INT,USR,SYS,REA,WRI> UCP/8/48/0x3f8<EDG,THR,REA,WRI,INV,QUA,PRC> UCF/1/48/0x60<REA,WRI>
Sep  1 19:58:21 freenas kernel: em0: link state changed to DOWN
Sep  1 19:58:21 freenas kernel: em0: link state changed to DOWN
Sep  1 19:58:21 freenas kernel: em0: link state changed to UP
Sep  1 19:58:21 freenas kernel: em0: link state changed to UP
Sep  1 19:58:23 freenas ntpd[2045]: ntpd 4.2.8p10-a (1): Starting
Sep  1 19:58:28 freenas root: /etc/rc: WARNING: failed precmd routine for minio
Sep  1 19:58:38 freenas bridge0: Ethernet address: 02:a9:46:42:10:00
Sep  1 19:58:38 freenas kernel: bridge0: link state changed to UP
Sep  1 19:58:38 freenas kernel: bridge0: link state changed to UP
Sep  1 19:58:38 freenas kernel: em0: promiscuous mode enabled
Sep  1 19:58:38 freenas epair0a: Ethernet address: 02:78:50:00:04:0a
Sep  1 19:58:38 freenas epair0b: Ethernet address: 02:78:a0:00:05:0b
Sep  1 19:58:38 freenas kernel: epair0a: link state changed to UP
Sep  1 19:58:38 freenas kernel: epair0a: link state changed to UP
Sep  1 19:58:38 freenas kernel: epair0b: link state changed to UP
Sep  1 19:58:38 freenas kernel: epair0b: link state changed to UP
Sep  1 19:58:38 freenas kernel: em0: link state changed to DOWN
Sep  1 19:58:38 freenas kernel: em0: link state changed to DOWN
Sep  1 19:58:38 freenas kernel: epair0a: promiscuous mode enabled
Sep  1 19:58:41 freenas epair1a: Ethernet address: 02:78:50:00:05:0a
Sep  1 19:58:41 freenas epair1b: Ethernet address: 02:78:a0:00:06:0b
Sep  1 19:58:41 freenas kernel: epair1a: link state changed to UP
Sep  1 19:58:41 freenas kernel: epair1a: link state changed to UP
Sep  1 19:58:41 freenas kernel: epair1b: link state changed to UP
Sep  1 19:58:41 freenas kernel: epair1b: link state changed to UP
Sep  1 19:58:41 freenas kernel: epair1a: promiscuous mode enabled
Sep  1 19:58:41 freenas kernel: em0: link state changed to UP
Sep  1 19:58:41 freenas kernel: em0: link state changed to UP
Sep  1 19:58:46 freenas dhclient: New IP Address (em0): 192.168.0.192
Sep  1 19:58:46 freenas dhclient: New Subnet Mask (em0): 255.255.255.0
Sep  1 19:58:46 freenas dhclient: New Broadcast Address (em0): 192.168.0.255
Sep  1 19:58:46 freenas dhclient: New Routers (em0): 192.168.0.1
Sep  1 19:58:49 freenas epair2a: Ethernet address: 02:78:50:00:06:0a
Sep  1 19:58:49 freenas epair2b: Ethernet address: 02:78:a0:00:07:0b
Sep  1 19:58:49 freenas kernel: epair2a: link state changed to UP
Sep  1 19:58:49 freenas kernel: epair2a: link state changed to UP
Sep  1 19:58:49 freenas kernel: epair2b: link state changed to UP
Sep  1 19:58:49 freenas kernel: epair2b: link state changed to UP
Sep  1 19:58:49 freenas kernel: epair2a: promiscuous mode enabled
Sep  1 19:58:53 freenas epair3a: Ethernet address: 02:78:50:00:07:0a
Sep  1 19:58:53 freenas epair3b: Ethernet address: 02:78:a0:00:08:0b
Sep  1 19:58:53 freenas kernel: epair3a: link state changed to UP
Sep  1 19:58:53 freenas kernel: epair3a: link state changed to UP
Sep  1 19:58:53 freenas kernel: epair3b: link state changed to UP
Sep  1 19:58:53 freenas kernel: epair3b: link state changed to UP
Sep  1 19:58:53 freenas kernel: epair3a: promiscuous mode enabled
Sep  1 19:58:57 freenas epair4a: Ethernet address: 02:78:50:00:08:0a
Sep  1 19:58:57 freenas epair4b: Ethernet address: 02:78:a0:00:09:0b
Sep  1 19:58:57 freenas kernel: epair4a: link state changed to UP
Sep  1 19:58:57 freenas kernel: epair4a: link state changed to UP
Sep  1 19:58:57 freenas kernel: epair4b: link state changed to UP
Sep  1 19:58:57 freenas kernel: epair4b: link state changed to UP
Sep  1 19:58:57 freenas kernel: epair4a: promiscuous mode enabled
Sep  1 19:58:59 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on em0
Sep  1 19:59:00 freenas tap0: Ethernet address: 00:bd:9a:ae:f8:00
Sep  1 19:59:00 freenas kernel: tap0: promiscuous mode enabled
Sep  1 19:59:01 freenas kernel: tap0: link state changed to UP
Sep  1 19:59:01 freenas kernel: tap0: link state changed to UP
Sep  1 19:59:21 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on em0
Sep  1 19:59:34 freenas kernel: em0: link state changed to DOWN
Sep  1 19:59:34 freenas kernel: em0: link state changed to DOWN
Sep  1 19:59:37 freenas kernel: em0: link state changed to UP
Sep  1 19:59:37 freenas kernel: em0: link state changed to UP
Sep  1 19:59:51 freenas kernel: em0: link state changed to DOWN
Sep  1 19:59:51 freenas kernel: em0: link state changed to DOWN
Sep  1 19:59:54 freenas kernel: em0: link state changed to UP
Sep  1 19:59:54 freenas kernel: em0: link state changed to UP
Sep  1 20:00:15 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on em0
Sep  1 20:00:16 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on em0
Sep  1 20:00:19 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on em0
Sep  1 20:00:37 freenas dhclient: New IP Address (em0): 192.168.0.192
Sep  1 20:00:37 freenas dhclient: New Subnet Mask (em0): 255.255.255.0
Sep  1 20:00:37 freenas dhclient: New Broadcast Address (em0): 192.168.0.255
Sep  1 20:00:37 freenas dhclient: New Routers (em0): 192.168.0.1
Sep  1 20:00:50 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 20:00:55 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on epair2b
Sep  1 20:01:33 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 20:01:34 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on epair2b
Sep  1 20:02:39 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 20:02:43 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 20:02:45 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on epair2b
Sep  1 20:03:21 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 20:03:25 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on epair2b
Sep  1 20:03:50 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 20:04:18 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on em0
Sep  1 20:04:33 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 20:13:52 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on epair2b
Sep  1 20:14:02 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on em0
Sep  1 20:23:53 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on em0
Sep  1 20:24:43 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 20:24:52 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on em0
Sep  1 20:28:27 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on em0
Sep  1 20:28:52 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  1 20:34:13 freenas kernel: arp: 192.168.0.9 moved from 40:98:ad:a0:c2:d0 to a0:f3:c1:ed:2d:fe on em0
Sep  1 20:35:47 freenas kernel: arp: 192.168.0.2 moved from 00:90:a9:d5:bf:d5 to 54:10:ec:c7:45:bb on epair2b
Sep  1 20:36:32 freenas kernel: arp: 192.168.0.2 moved from 54:10:ec:c7:45:bb to 00:90:a9:d5:bf:d5 on epair2b
Sep  2 00:00:00 freenas syslog-ng[1631]: Configuration reload request received, reloading configuration;
Sep  2 00:00:00 freenas ZFS: vdev state changed, pool_guid=1766491209078687851 vdev_guid=12035265332435584596
 

kdragon75

Wizard
Joined
Aug 7, 2016
Messages
2,457
I don't see the FreeNAS network up after reboot so I don't see anything that could be related to FreeNAS. Are the router and FreeNAS clocks in near perfect sync? If not it make this much more difficult to correlate events without known matching events.
 

JamesWorts

Dabbler
Joined
Apr 29, 2017
Messages
17
Hi Kdragon75,

Thanks for your response,

I cant see this issue being anything other than FreeNAS in some way as it happens every single time i reboot FreeNAS

Time wise, they are pretty much spot on, pic attached.
 

Attachments

  • FreeNAS.PNG
    FreeNAS.PNG
    120.9 KB · Views: 397

kdragon75

Wizard
Joined
Aug 7, 2016
Messages
2,457
What about your network switch? How is your NAS connected to the rest of your network?
 

kdragon75

Wizard
Joined
Aug 7, 2016
Messages
2,457
Try disabling all of your jails auto start, makesure you NAS is only connected by one cable and see if that makes a difference.
 

kdragon75

Wizard
Joined
Aug 7, 2016
Messages
2,457
Are your jails using 192.168.0.9 & 192.168.0.2?
 

JamesWorts

Dabbler
Joined
Apr 29, 2017
Messages
17
Nope.

I can disable autostart and reboot, see if that solves it, but I would need to fix this, cant be having to manually start each one.
 

Attachments

  • jails.JPG
    jails.JPG
    56.5 KB · Views: 290
Status
Not open for further replies.
Top