weird transmission problem

vicesig · Apr 17, 2016

Hello everyone, I'm a newbie both of the forum and of freeNAS.
I installed freeNAS in a USB stick on a dedicated machine (Intel g840, 6 gigs of RAM, 3HDD 500mbyte each in raidZ). No problem copying in and out from CIFS at first.
I installed Transmission, I give the permissions to every user inthe pool and in the jails for letting Transmission do its work, I try a torrent to see if it works, it downloads flawlessy and I can copy at full gigabit speed through CIFS.
Then, I reboot, and everything falls apart: the transmission plugin does not boot up ("some error occoured"), I can't copy in or out through CIFS (slow speed the first seconds, then it goes to zero speed and stays there).
I tried everything: deleting the plugin, deleting the jail, reinstalling the plugin, giving and taking permission and putting recursive permission. The only way to doing it work again is by deleting the pool and starting all over again.
I searched in the forum and in Google but haven't found anybody with the same issue.
Anyone willing to help?

dlavigne · Apr 17, 2016

Build version (from System -> Information)?

Anything in /var/log/messages (paste it using Insert -> Code).

vicesig · Apr 17, 2016

build: FreeNAS-9.10-STABLE-201604140622 (8781269)
I can't copy anything out from the server, but I managed to copy this from the footer. The final line appears when I try to boot the plugin.

Code:

Apr 17 15:27:29 freenas syslog-ng[1250]: syslog-ng starting up; version='3.6.4'
Apr 17 15:27:29 freenas 90 second watchdog timeout expired. Shutdown terminated.
Apr 17 15:27:29 freenas wbwd0: Failed to disable watchdog: 0x86.
Apr 17 15:27:29 freenas Waiting (max 60 seconds) for system process `vnlru' to stop...done
Apr 17 15:27:29 freenas Waiting (max 60 seconds) for system process `bufdaemon' to stop...done
Apr 17 15:27:29 freenas Waiting (max 60 seconds) for system process `syncer' to stop...
Apr 17 15:27:29 freenas Syncing disks, vnodes remaining...0 0 0 0 0 done
Apr 17 15:27:29 freenas All buffers synced.
Apr 17 15:27:29 freenas Copyright (c) 1992-2016 The FreeBSD Project.
Apr 17 15:27:29 freenas Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
Apr 17 15:27:29 freenas     The Regents of the University of California. All rights reserved.
Apr 17 15:27:29 freenas FreeBSD is a registered trademark of The FreeBSD Foundation.
Apr 17 15:27:29 freenas FreeBSD 10.3-RELEASE #0 1b72f49(HEAD): Wed Apr 13 23:35:10 PDT 2016
Apr 17 15:27:29 freenas root@build.ixsystems.com:/tank/home/nightlies/CVE910/_BE/objs/tank/home/nightlies/CVE910/_BE/trueos/sys/FreeNAS.amd64 amd64
Apr 17 15:27:29 freenas FreeBSD clang version 3.4.1 (tags/RELEASE_34/dot1-final 208032) 20140512
Apr 17 15:27:29 freenas CPU: Intel(R) Pentium(R) CPU G840 @ 2.80GHz (2793.72-MHz K8-class CPU)
Apr 17 15:27:29 freenas Origin="GenuineIntel"  Id=0x206a7  Family=0x6  Model=0x2a  Stepping=7
Apr 17 15:27:29 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>
Apr 17 15:27:29 freenas Features2=0xd9ae3bf<SSE3,PCLMULQDQ,DTES64,MON,DS_CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,POPCNT,TSCDLT,XSAVE,OSXSAVE>
Apr 17 15:27:29 freenas AMD Features=0x28100800<SYSCALL,NX,RDTSCP,LM>
Apr 17 15:27:29 freenas AMD Features2=0x1<LAHF>
Apr 17 15:27:29 freenas XSAVE Features=0x1<XSAVEOPT>
Apr 17 15:27:29 freenas VT-x: (disabled in BIOS) PAT,HLT,MTF,PAUSE,EPT,UG,VPID
Apr 17 15:27:29 freenas TSC: P-state invariant, performance statistics
Apr 17 15:27:29 freenas real memory  = 7514095616 (7166 MB)
Apr 17 15:27:29 freenas avail memory = 5887799296 (5615 MB)
Apr 17 15:27:29 freenas Event timer "LAPIC" quality 600
Apr 17 15:27:29 freenas ACPI APIC Table: <ALASKA A M I>
Apr 17 15:27:29 freenas FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
Apr 17 15:27:29 freenas FreeBSD/SMP: 1 package(s) x 2 core(s)
Apr 17 15:27:29 freenas cpu0 (BSP): APIC ID:  0
Apr 17 15:27:29 freenas cpu1 (AP): APIC ID:  2
Apr 17 15:27:29 freenas random: <Software, Yarrow> initialized
Apr 17 15:27:29 freenas WARNING: VIMAGE (virtualized network stack) is a highly experimental feature.
Apr 17 15:27:29 freenas ioapic0 <Version 2.0> irqs 0-23 on motherboard
Apr 17 15:27:29 freenas kbd1 at kbdmux0
Apr 17 15:27:29 freenas cryptosoft0: <software crypto> on motherboard
Apr 17 15:27:29 freenas aesni0: No AESNI support.
Apr 17 15:27:29 freenas padlock0: No ACE support.
Apr 17 15:27:29 freenas acpi0: <ALASKA A M I> on motherboard
Apr 17 15:27:29 freenas acpi0: Power Button (fixed)
Apr 17 15:27:29 freenas cpu0: <ACPI CPU> on acpi0
Apr 17 15:27:29 freenas cpu1: <ACPI CPU> on acpi0
Apr 17 15:27:29 freenas attimer0: <AT timer> port 0x40-0x43 irq 0 on acpi0
Apr 17 15:27:29 freenas Timecounter "i8254" frequency 1193182 Hz quality 0
Apr 17 15:27:29 freenas Event timer "i8254" frequency 1193182 Hz quality 100
Apr 17 15:27:29 freenas atrtc0: <AT realtime clock> port 0x70-0x71 irq 8 on acpi0
Apr 17 15:27:29 freenas Event timer "RTC" frequency 32768 Hz quality 0
Apr 17 15:27:29 freenas hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
Apr 17 15:27:29 freenas Timecounter "HPET" frequency 14318180 Hz quality 950
Apr 17 15:27:29 freenas Event timer "HPET" frequency 14318180 Hz quality 550
Apr 17 15:27:29 freenas Event timer "HPET1" frequency 14318180 Hz quality 440
Apr 17 15:27:29 freenas Event timer "HPET2" frequency 14318180 Hz quality 440
Apr 17 15:27:29 freenas Event timer "HPET3" frequency 14318180 Hz quality 440
Apr 17 15:27:29 freenas Event timer "HPET4" frequency 14318180 Hz quality 440
Apr 17 15:27:29 freenas Event timer "HPET5" frequency 14318180 Hz quality 440
Apr 17 15:27:29 freenas Event timer "HPET6" frequency 14318180 Hz quality 440
Apr 17 15:27:29 freenas Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
Apr 17 15:27:29 freenas acpi_timer0: <24-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0
Apr 17 15:27:29 freenas pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
Apr 17 15:27:29 freenas pci0: <ACPI PCI bus> on pcib0
Apr 17 15:27:29 freenas pcib1: <ACPI PCI-PCI bridge> irq 16 at device 1.0 on pci0
Apr 17 15:27:29 freenas pci1: <ACPI PCI bus> on pcib1
Apr 17 15:27:29 freenas vgapci0: <VGA-compatible display> port 0xf000-0xf03f mem 0xfe000000-0xfe3fffff,0xc0000000-0xcfffffff irq 16 at device 2.0 on pci0
Apr 17 15:27:29 freenas agp0: <SandyBridge desktop GT1 IG> on vgapci0
Apr 17 15:27:29 freenas agp0: aperture size is 256M, detected 262140k stolen memory
Apr 17 15:27:29 freenas vgapci0: Boot video device
Apr 17 15:27:29 freenas pci0: <simple comms> at device 22.0 (no driver attached)
Apr 17 15:27:29 freenas ehci0: <EHCI (generic) USB 2.0 controller> mem 0xfe406000-0xfe4063ff irq 23 at device 26.0 on pci0
Apr 17 15:27:29 freenas usbus0: EHCI version 1.0
Apr 17 15:27:29 freenas usbus0 on ehci0
Apr 17 15:27:29 freenas pci0: <multimedia, HDA> at device 27.0 (no driver attached)
Apr 17 15:27:29 freenas pcib2: <ACPI PCI-PCI bridge> irq 17 at device 28.0 on pci0
Apr 17 15:27:29 freenas pci2: <ACPI PCI bus> on pcib2
Apr 17 15:27:29 freenas pcib3: <ACPI PCI-PCI bridge> irq 16 at device 28.1 on pci0
Apr 17 15:27:29 freenas pci3: <ACPI PCI bus> on pcib3
Apr 17 15:27:29 freenas pcib4: <ACPI PCI-PCI bridge> irq 18 at device 28.2 on pci0
Apr 17 15:27:29 freenas pci4: <ACPI PCI bus> on pcib4
Apr 17 15:27:29 freenas pcib5: <ACPI PCI-PCI bridge> irq 18 at device 0.0 on pci4
Apr 17 15:27:29 freenas pci5: <ACPI PCI bus> on pcib5
Apr 17 15:27:29 freenas pcib6: <ACPI PCI-PCI bridge> irq 19 at device 28.3 on pci0
Apr 17 15:27:29 freenas pci6: <ACPI PCI bus> on pcib6
Apr 17 15:27:29 freenas re0: <RealTek 8168/8111 B/C/CP/D/DP/E/F/G PCIe Gigabit Ethernet> port 0xe000-0xe0ff mem 0xd0004000-0xd0004fff,0xd0000000-0xd0003fff irq 19 at device 0.0 on pci6
Apr 17 15:27:29 freenas re0: Using 1 MSI-X message
Apr 17 15:27:29 freenas re0: Chip rev. 0x2c000000
Apr 17 15:27:29 freenas re0: MAC rev. 0x00200000
Apr 17 15:27:29 freenas miibus0: <MII bus> on re0
Apr 17 15:27:29 freenas rgephy0: <RTL8169S/8110S/8211 1000BASE-T media interface> PHY 1 on miibus0
Apr 17 15:27:29 freenas rgephy0:  none, 10baseT, 10baseT-FDX, 10baseT-FDX-flow, 100baseTX, 100baseTX-FDX, 100baseTX-FDX-flow, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, 1000baseT-FDX-flow, 1000baseT-FDX-flow-master, auto, auto-flow
Apr 17 15:27:29 freenas re0: Using defaults for TSO: 65518/35/2048
Apr 17 15:27:29 freenas re0: Ethernet address: c8:60:00:85:44:80
Apr 17 15:27:29 freenas pcib7: <ACPI PCI-PCI bridge> irq 17 at device 28.4 on pci0
Apr 17 15:27:29 freenas pci7: <ACPI PCI bus> on pcib7
Apr 17 15:27:29 freenas pcib8: <ACPI PCI-PCI bridge> irq 16 at device 28.5 on pci0
Apr 17 15:27:29 freenas pci8: <ACPI PCI bus> on pcib8
Apr 17 15:27:29 freenas ehci1: <EHCI (generic) USB 2.0 controller> mem 0xfe405000-0xfe4053ff irq 23 at device 29.0 on pci0
Apr 17 15:27:29 freenas usbus1: EHCI version 1.0
Apr 17 15:27:29 freenas usbus1 on ehci1
Apr 17 15:27:29 freenas isab0: <PCI-ISA bridge> at device 31.0 on pci0
Apr 17 15:27:29 freenas isa0: <ISA bus> on isab0
Apr 17 15:27:29 freenas atapci0: <Intel Cougar Point SATA600 controller> port 0xf110-0xf117,0xf100-0xf103,0xf0f0-0xf0f7,0xf0e0-0xf0e3,0xf0d0-0xf0df,0xf0c0-0xf0cf irq 20 at device 31.2 on pci0
Apr 17 15:27:29 freenas ata2: <ATA channel> at channel 0 on atapci0
Apr 17 15:27:29 freenas ata3: <ATA channel> at channel 1 on atapci0
Apr 17 15:27:29 freenas atapci1: <Intel Cougar Point SATA300 controller> port 0xf0b0-0xf0b7,0xf0a0-0xf0a3,0xf090-0xf097,0xf080-0xf083,0xf070-0xf07f,0xf060-0xf06f irq 20 at device 31.5 on pci0
Apr 17 15:27:29 freenas ata4: <ATA channel> at channel 0 on atapci1
Apr 17 15:27:29 freenas ata5: <ATA channel> at channel 1 on atapci1
Apr 17 15:27:29 freenas acpi_button0: <Power Button> on acpi0
Apr 17 15:27:29 freenas uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
Apr 17 15:27:29 freenas ichwd0: <Intel Cougar Point watchdog timer> on isa0
Apr 17 15:27:29 freenas wbwd0: <Nuvoton NCT6776 (0xc3/0x33) Watchdog Timer> at port 0x2e-0x2f on isa0
Apr 17 15:27:29 freenas sc0: <System console> at flags 0x100 on isa0
Apr 17 15:27:29 freenas sc0: VGA <16 virtual consoles, flags=0x300>
Apr 17 15:27:29 freenas vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Apr 17 15:27:29 freenas atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
Apr 17 15:27:29 freenas atkbd0: <AT Keyboard> irq 1 on atkbdc0
Apr 17 15:27:29 freenas kbd0 at atkbd0
Apr 17 15:27:29 freenas atkbd0: [GIANT-LOCKED]
Apr 17 15:27:29 freenas coretemp0: <CPU On-Die Thermal Sensors> on cpu0
Apr 17 15:27:29 freenas est0: <Enhanced SpeedStep Frequency Control> on cpu0
Apr 17 15:27:29 freenas coretemp1: <CPU On-Die Thermal Sensors> on cpu1
Apr 17 15:27:29 freenas est1: <Enhanced SpeedStep Frequency Control> on cpu1
Apr 17 15:27:29 freenas ZFS filesystem version: 5
Apr 17 15:27:29 freenas ZFS storage pool version: features support (5000)
Apr 17 15:27:29 freenas Timecounters tick every 1.000 msec
Apr 17 15:27:29 freenas ipfw2 (+ipv6) initialized, divert enabled, nat enabled, default to accept, logging disabled
Apr 17 15:27:29 freenas random: unblocking device.
Apr 17 15:27:29 freenas usbus0: 480Mbps High Speed USB v2.0
Apr 17 15:27:29 freenas usbus1: 480Mbps High Speed USB v2.0
Apr 17 15:27:29 freenas ugen1.1: <Intel> at usbus1
Apr 17 15:27:29 freenas uhub0: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus1
Apr 17 15:27:29 freenas ugen0.1: <Intel> at usbus0
Apr 17 15:27:29 freenas uhub1: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus0
Apr 17 15:27:29 freenas uhub0: 2 ports with 2 removable, self powered
Apr 17 15:27:29 freenas uhub1: 2 ports with 2 removable, self powered
Apr 17 15:27:29 freenas ugen0.2: <vendor 0x8087> at usbus0
Apr 17 15:27:29 freenas uhub2: <vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, addr 2> on usbus0
Apr 17 15:27:29 freenas ugen1.2: <vendor 0x8087> at usbus1
Apr 17 15:27:29 freenas uhub3: <vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, addr 2> on usbus1
Apr 17 15:27:29 freenas uhub2: 4 ports with 4 removable, self powered
Apr 17 15:27:29 freenas uhub3: 6 ports with 6 removable, self powered
Apr 17 15:27:29 freenas ugen1.3: <Kingston> at usbus1
Apr 17 15:27:29 freenas umass0: <Kingston DataTraveler 2.0, class 0/0, rev 2.00/2.00, addr 3> on usbus1
Apr 17 15:27:29 freenas umass0:  SCSI over Bulk-Only; quirks = 0xc100
Apr 17 15:27:29 freenas umass0:5:0:-1: Attached to scbus5
Apr 17 15:27:29 freenas ugen1.4: <Logitech> at usbus1
Apr 17 15:27:29 freenas ada0 at ata2 bus 0 scbus0 target 0 lun 0
Apr 17 15:27:29 freenas ada0: <WDC WD5000AZRX-00A8LB0 01.01A01> ATA8-ACS SATA 3.x device
Apr 17 15:27:29 freenas ada0: Serial Number WD-WMC1U2915097
Apr 17 15:27:29 freenas cd0 at ata5 bus 0 scbus3 target 0 lun 0
Apr 17 15:27:29 freenas cd0: <Optiarc DVD RW AD-7261S 1.01> Removable CD-ROM SCSI device
Apr 17 15:27:29 freenas cd0: 150.000MB/s transfers (SATA 1.x, UDMA5, ATAPI 12bytes, PIO 8192bytes)
Apr 17 15:27:29 freenas cd0: Attempt to query device size failed: NOT READY, Medium not present - tray closed
Apr 17 15:27:29 freenas ada0: 300.000MB/s transfers (SATA 2.x, UDMA5, PIO 8192bytes)
Apr 17 15:27:29 freenas ada0: 476940MB (976773168 512 byte sectors)
Apr 17 15:27:29 freenas ada0: quirks=0x1<4K>
Apr 17 15:27:29 freenas ada0: Previously was known as ad4
Apr 17 15:27:29 freenas ada1 at ata3 bus 0 scbus1 target 0 lun 0
Apr 17 15:27:29 freenas ada1: <ST500DM002-1BD142 KC44> ATA8-ACS SATA 3.x device
Apr 17 15:27:29 freenas ada1: Serial Number Z2ACRBJZ
Apr 17 15:27:29 freenas ada1: 300.000MB/s transfers (SATA 2.x, UDMA5, PIO 8192bytes)
Apr 17 15:27:29 freenas ada1: 476940MB (976773168 512 byte sectors)
Apr 17 15:27:29 freenas ada1: quirks=0x1<4K>
Apr 17 15:27:29 freenas ada1: Previously was known as ad6
Apr 17 15:27:29 freenas ada2 at ata4 bus 0 scbus2 target 0 lun 0
Apr 17 15:27:29 freenas ada2: <ST500LT012-1DG142 0001SDM1> ATA8-ACS SATA 3.x device
Apr 17 15:27:29 freenas ada2: Serial Number S3P74ML5
Apr 17 15:27:29 freenas ada2: 300.000MB/s transfers (SATA 2.x, UDMA5, PIO 8192bytes)
Apr 17 15:27:29 freenas ada2: 476940MB (976773168 512 byte sectors)
Apr 17 15:27:29 freenas ada2: quirks=0x1<4K>
Apr 17 15:27:29 freenas ada2: Previously was known as ad8
Apr 17 15:27:29 freenas da0 at umass-sim0 bus 0 scbus5 target 0 lun 0
Apr 17 15:27:29 freenas da0: <Kingston DataTraveler 2.0 1.00> Removable Direct Access SCSI-2 device
Apr 17 15:27:29 freenas da0: Serial Number 0019E06B07A9A8A1FFFF0091
Apr 17 15:27:29 freenas da0: 40.000MB/s transfers
Apr 17 15:27:29 freenas da0: 7692MB (15753216 512 byte sectors)
Apr 17 15:27:29 freenas da0: quirks=0x3<NO_SYNC_CACHE,NO_6_BYTE>
Apr 17 15:27:29 freenas SMP: AP CPU #1 Launched!
Apr 17 15:27:29 freenas Timecounter "TSC-low" frequency 1396858588 Hz quality 1000
Apr 17 15:27:29 freenas Trying to mount root from zfs:freenas-boot/ROOT/9.10-STABLE-201604140622 []...
Apr 17 15:27:29 freenas GEOM_RAID5: Module loaded, version 1.3.20140711.62 (rev f91e28e40bf7)
Apr 17 15:27:29 freenas kernel: re0: link state changed to UP
Apr 17 15:27:29 freenas kernel: re0: link state changed to UP
Apr 17 15:27:29 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>
Apr 17 15:27:29 freenas kernel: re0: link state changed to DOWN
Apr 17 15:27:29 freenas kernel: re0: link state changed to DOWN
Apr 17 15:27:29 freenas kernel: re0: link state changed to UP
Apr 17 15:27:29 freenas kernel: re0: link state changed to UP
Apr 17 15:27:29 freenas ums0: <Logitech Optical USB Mouse, class 0/0, rev 2.00/3.40, addr 4> on usbus1
Apr 17 15:27:29 freenas ums0: 3 buttons and [XYZ] coordinates ID=0
Apr 17 15:27:29 freenas vboxdrv: fAsync=0 offMin=0x2e4 offMax=0xaa4
Apr 17 15:27:30 freenas ntpd[1534]: ntpd 4.2.8p6-a (1): Starting
Apr 17 15:27:33 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Apr 17 15:27:33 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Apr 17 15:27:33 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/net -d 0 setlocalsid S-1-5-21-2935517764-1545820137-1794740574
Apr 17 15:27:33 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpB2xNYV -s /usr/local/etc/smb4.conf -e tdbsam:/var/db/samba4/private/passdb.tdb
Apr 17 15:27:33 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/pdbedit -d 0 -L
Apr 17 15:27:34 freenas root: /etc/rc: WARNING: failed precmd routine for vmware_guestd
Apr 17 15:27:38 freenas smartd[1972]: Device: /dev/ada2, FAILED SMART self-check. BACK UP DATA NOW!
Apr 17 15:27:38 freenas smartd[1972]: Device: /dev/ada2, Failed SMART usage Attribute: 5 Reallocated_Sector_Ct.
Apr 17 15:27:40 freenas mDNSResponder: mDNSResponder (Engineering Build) (Apr 14 2016 06:45:32) starting
Apr 17 15:27:40 freenas mDNSResponder:   8: Listening for incoming Unix Domain Socket client requests
Apr 17 15:27:40 freenas mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Apr 17 15:27:40 freenas mDNSResponder: CheckNATMappings: Failed to allocate port 5350 UDP multicast socket for PCP & NAT-PMP announcements
Apr 17 15:27:44 freenas mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 freenas.local. (Addr) that's already in the list
Apr 17 15:27:44 freenas mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 25.0.0.10.in-addr.arpa. (PTR) that's already in the list
Apr 17 15:28:40 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Apr 17 15:28:40 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Apr 17 15:28:40 freenas notifier: Performing sanity check on Samba configuration: OK
Apr 17 15:28:41 freenas syslog-ng[3608]: syslog-ng starting up; version='3.6.4'
Apr 17 15:28:41 freenas kernel: pid 1250 (syslog-ng), uid 0: exited on signal 6 (core dumped)
Apr 17 15:28:42 freenas mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 freenas.local. (Addr) that's already in the list
Apr 17 15:28:42 freenas mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 25.0.0.10.in-addr.arpa. (PTR) that's already in the list
Apr 17 15:28:58 freenas notifier: dd: /dev/ada0: Operation not permitted
Apr 17 15:28:58 freenas notifier: dd: /dev/ada0: Operation not permitted
Apr 17 15:28:58 freenas manage.py: [middleware.exceptions:37] [MiddlewareError: Unable to GPT format the disk "ada0"]
Apr 17 15:29:08 freenas notifier: 32+0 records in
Apr 17 15:29:08 freenas notifier: 32+0 records out
Apr 17 15:29:08 freenas notifier: 33554432 bytes transferred in 0.871653 secs (38495179 bytes/sec)
Apr 17 15:29:08 freenas GEOM: ada0: the primary GPT table is corrupt or invalid.
Apr 17 15:29:08 freenas GEOM: ada0: using the secondary instead -- recovery strongly advised.
Apr 17 15:29:08 freenas notifier: dd: /dev/ada0: short write on character device
Apr 17 15:29:08 freenas notifier: dd: /dev/ada0: end of device
Apr 17 15:29:08 freenas notifier: 33+0 records in
Apr 17 15:29:08 freenas notifier: 32+1 records out
Apr 17 15:29:08 freenas notifier: 33579008 bytes transferred in 0.406139 secs (82678631 bytes/sec)
Apr 17 15:29:09 freenas GEOM: ada1: the primary GPT table is corrupt or invalid.
Apr 17 15:29:09 freenas GEOM: ada1: using the secondary instead -- recovery strongly advised.
Apr 17 15:29:09 freenas notifier: 32+0 records in
Apr 17 15:29:09 freenas notifier: 32+0 records out
Apr 17 15:29:09 freenas notifier: 33554432 bytes transferred in 0.358789 secs (93521360 bytes/sec)
Apr 17 15:29:09 freenas notifier: dd: /dev/ada1: short write on character device
Apr 17 15:29:09 freenas notifier: dd: /dev/ada1: end of device
Apr 17 15:29:09 freenas notifier: 33+0 records in
Apr 17 15:29:09 freenas notifier: 32+1 records out
Apr 17 15:29:09 freenas notifier: 33579008 bytes transferred in 0.455107 secs (73782671 bytes/sec)
Apr 17 15:29:11 freenas notifier: 32+0 records in
Apr 17 15:29:11 freenas notifier: 32+0 records out
Apr 17 15:29:11 freenas notifier: 33554432 bytes transferred in 0.364394 secs (92082846 bytes/sec)
Apr 17 15:29:11 freenas GEOM: ada2: the primary GPT table is corrupt or invalid.
Apr 17 15:29:11 freenas GEOM: ada2: using the secondary instead -- recovery strongly advised.
Apr 17 15:29:14 freenas notifier: dd: /dev/ada2: short write on character device
Apr 17 15:29:14 freenas notifier: dd: /dev/ada2: end of device
Apr 17 15:29:14 freenas notifier: 33+0 records in
Apr 17 15:29:14 freenas notifier: 32+1 records out
Apr 17 15:29:14 freenas notifier: 33579008 bytes transferred in 3.674970 secs (9137220 bytes/sec)
Apr 17 15:29:15 freenas notifier: vfs.zfs.vdev.larger_ashift_minimal: 0 -> 1
Apr 17 22:29:15 freenas devd: Executing 'logger -p kern.notice -t ZFS 'vdev state changed, pool_guid=17302377520989544210 vdev_guid=10232348827713496374''
Apr 17 15:29:15 freenas ZFS: vdev state changed, pool_guid=17302377520989544210 vdev_guid=10232348827713496374
Apr 17 22:29:15 freenas devd: Executing 'logger -p kern.notice -t ZFS 'vdev state changed, pool_guid=17302377520989544210 vdev_guid=292201774566710442''
Apr 17 15:29:15 freenas ZFS: vdev state changed, pool_guid=17302377520989544210 vdev_guid=292201774566710442
Apr 17 22:29:15 freenas devd: Executing 'logger -p kern.notice -t ZFS 'vdev state changed, pool_guid=17302377520989544210 vdev_guid=9344248354140503450''
Apr 17 15:29:15 freenas ZFS: vdev state changed, pool_guid=17302377520989544210 vdev_guid=9344248354140503450
Apr 17 15:29:22 freenas notifier: vfs.zfs.vdev.larger_ashift_minimal: 1 -> 0
Apr 17 15:29:25 freenas GEOM_ELI: Device ada0p1.eli created.
Apr 17 15:29:25 freenas GEOM_ELI: Encryption: AES-XTS 128
Apr 17 15:29:25 freenas GEOM_ELI:     Crypto: software
Apr 17 15:29:25 freenas GEOM_ELI: Device ada1p1.eli created.
Apr 17 15:29:25 freenas GEOM_ELI: Encryption: AES-XTS 128
Apr 17 15:29:25 freenas GEOM_ELI:     Crypto: software
Apr 17 15:29:25 freenas GEOM_ELI: Device ada2p1.eli created.
Apr 17 15:29:25 freenas GEOM_ELI: Encryption: AES-XTS 128
Apr 17 15:29:25 freenas GEOM_ELI:     Crypto: software
Apr 17 15:29:25 freenas notifier: Mounting late file systems:.
Apr 17 15:29:25 freenas notifier: Stopping collectd.
Apr 17 15:29:25 freenas notifier: Waiting for PIDS: 2562.
Apr 17 15:29:26 freenas notifier: Starting collectd.
Apr 17 15:29:30 freenas notifier: Stopping collectd.
Apr 17 15:29:30 freenas notifier: Waiting for PIDS: 4363.
Apr 17 15:29:30 freenas notifier: Starting collectd.
Apr 17 15:29:30 freenas notifier: kern.corefile: %N.core -> /var/db/system/cores/%N.core
Apr 17 15:29:31 freenas syslog-ng[4731]: syslog-ng starting up; version='3.6.4'
Apr 17 15:29:32 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Apr 17 15:29:32 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/net -d 0 setlocalsid S-1-5-21-2935517764-1545820137-1794740574
Apr 17 15:29:32 freenas notifier: Unable to set SID to S-1-5-21-2935517764-1545820137-1794740574
Apr 17 15:29:32 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Apr 17 15:29:32 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Apr 17 15:29:32 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/net -d 0 setlocalsid S-1-5-21-2935517764-1545820137-1794740574
Apr 17 15:29:33 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpXCHev2 -s /usr/local/etc/smb4.conf -e tdbsam:/var/db/samba4/private/passdb.tdb
Apr 17 15:29:33 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/smbpasswd -e 'root'
Apr 17 15:29:33 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/pdbedit -d 0 -L
Apr 17 15:29:33 freenas generate_smb4_conf.py: [common.pipesubr:61] Popen()ing: /usr/local/bin/net -d 0 sam rights grant root SeTakeOwnershipPrivilege SeBackupPrivilege SeRestorePrivilege
Apr 17 15:29:33 freenas notifier: Importing account for root...ok
Apr 17 15:29:33 freenas notifier: Enabled user root.
Apr 17 15:29:33 freenas notifier: Granted SeTakeOwnershipPrivilege to FREENAS\root
Apr 17 15:29:33 freenas notifier: Granted SeBackupPrivilege to FREENAS\root
Apr 17 15:29:33 freenas notifier: Granted SeRestorePrivilege to FREENAS\root
Apr 17 15:29:33 freenas notifier: winbindd not running? (check /var/run/samba/winbindd.pid).
Apr 17 15:29:33 freenas notifier: smbd not running? (check /var/run/samba/smbd.pid).
Apr 17 15:29:33 freenas notifier: nmbd not running? (check /var/run/samba/nmbd.pid).
Apr 17 15:29:34 freenas notifier: Stopping mdnsd.
Apr 17 15:29:34 freenas mDNSResponder: mDNSResponder (Engineering Build) (Apr 14 2016 06:45:32) stopping
Apr 17 15:29:34 freenas mDNSResponder: mDNS_FinalExit failed to send goodbye for: 0000000801483580 01   26 _http._tcp.local. PTR freenas._http._tcp.local.
Apr 17 15:29:34 freenas notifier: Starting mdnsd.
Apr 17 15:29:34 freenas mDNSResponder: mDNSResponder (Engineering Build) (Apr 14 2016 06:45:32) starting
Apr 17 15:29:34 freenas mDNSResponder:  10: Listening for incoming Unix Domain Socket client requests
Apr 17 15:29:34 freenas mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Apr 17 15:29:34 freenas mDNSResponder: CheckNATMappings: Failed to allocate port 5350 UDP multicast socket for PCP & NAT-PMP announcements
Apr 17 15:29:35 freenas mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 freenas.local. (Addr) that's already in the list
Apr 17 15:29:35 freenas mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 25.0.0.10.in-addr.arpa. (PTR) that's already in the list
Apr 17 15:29:36 freenas notifier: Stopping smartd.
Apr 17 15:29:36 freenas notifier: Waiting for PIDS: 1982.
Apr 17 15:29:37 freenas notifier: smartd not running? (check /var/run/smartd.pid).
Apr 17 15:29:37 freenas notifier: Starting smartd.
Apr 17 15:29:38 freenas smartd[5072]: Device: /dev/ada2, FAILED SMART self-check. BACK UP DATA NOW!
Apr 17 15:29:38 freenas smartd[5072]: Device: /dev/ada2, Failed SMART usage Attribute: 5 Reallocated_Sector_Ct.
Apr 17 15:33:11 freenas warden: Building new Jail... Please wait...
Apr 17 15:33:11 freenas warden: zfs clone main/.warden-template-pluginjail@clean main/transmission_1
Apr 17 15:33:14 freenas warden: Success!
Apr 17 15:33:14 freenas warden: Jail created at /mnt/main/transmission_1
Apr 17 22:33:14 freenas devd: Executing '/etc/pccard_ether bridge0 start'
Apr 17 15:33:15 freenas bridge0: Ethernet address: 02:54:22:9d:8f:00
Apr 17 15:33:15 freenas kernel: bridge0: link state changed to UP
Apr 17 15:33:15 freenas kernel: bridge0: link state changed to UP
Apr 17 15:33:15 freenas kernel: re0: promiscuous mode enabled
Apr 17 15:33:15 freenas epair0a: Ethernet address: 02:ff:20:00:04:0a
Apr 17 15:33:15 freenas epair0b: Ethernet address: 02:ff:70:00:05:0b
Apr 17 15:33:15 freenas kernel: epair0a: link state changed to UP
Apr 17 15:33:15 freenas kernel: epair0a: link state changed to UP
Apr 17 15:33:15 freenas kernel: epair0b: link state changed to UP
Apr 17 15:33:15 freenas kernel: epair0b: link state changed to UP
Apr 17 15:33:15 freenas kernel: re0: link state changed to DOWN
Apr 17 15:33:15 freenas kernel: re0: link state changed to DOWN
Apr 17 15:33:15 freenas kernel: epair0a: promiscuous mode enabled
Apr 17 22:33:15 freenas devd: Executing '/etc/pccard_ether epair0a start'
Apr 17 22:33:15 freenas devd: Executing '/etc/pccard_ether epair0b start'
Apr 17 22:33:15 freenas devd: Executing '/etc/rc.d/dhclient quietstart epair0a'
Apr 17 15:33:15 freenas kernel: ng_ether_ifnet_arrival_event: can't re-name node epair0b
Apr 17 15:33:15 freenas kernel: ng_ether_ifnet_arrival_event: can't re-name node epair0b
Apr 17 22:33:19 freenas devd: Executing '/etc/rc.d/dhclient quietstart re0'
Apr 17 15:33:19 freenas kernel: re0: link state changed to UP
Apr 17 15:33:19 freenas kernel: re0: link state changed to UP
Apr 17 15:33:27 freenas dhclient: New IP Address (re0): 10.0.0.25
Apr 17 15:33:27 freenas dhclient: New Subnet Mask (re0): 255.255.255.0
Apr 17 15:33:27 freenas dhclient: New Broadcast Address (re0): 10.0.0.255
Apr 17 15:33:27 freenas dhclient: New Routers (re0): 10.0.0.1
Apr 17 15:33:28 freenas notifier: Performing sanity check on nginx configuration:
Apr 17 15:33:28 freenas notifier: nginx: the configuration file /usr/local/etc/nginx/nginx.conf syntax is ok
Apr 17 15:33:28 freenas notifier: nginx: configuration file /usr/local/etc/nginx/nginx.conf test is successful
Apr 17 15:33:30 freenas mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 00000008014D2D60 freenas.local. (Addr) that's already in the list
Apr 17 15:33:30 freenas mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 00000008014D3180 25.0.0.10.in-addr.arpa. (PTR) that's already in the list
Apr 17 15:33:32 freenas manage.py: [freeadmin.navtree:629] An error occurred while unserializing from http://10.0.0.25/plugins/transmission/1/_s/treemenu: No JSON object could be decoded
Apr 17 15:34:12 freenas manage.py: [plugins.utils:91] Couldn't retrieve http://10.0.0.25/plugins/transmission/1/_s/status: No JSON object could be decoded
Apr 17 15:34:31 freenas manage.py: [freeadmin.navtree:629] An error occurred while unserializing from http://10.0.0.25/plugins/transmission/1/_s/treemenu: No JSON object could be decoded
Apr 17 15:34:44 freenas manage.py: [freeadmin.navtree:629] An error occurred while unserializing from http://10.0.0.25/plugins/transmission/1/_s/treemenu: No JSON object could be decoded

Stop refresh

dlavigne · Apr 18, 2016

The Realtek card and 6GB of RAM aren't helping...

vicesig · Apr 18, 2016

I made it using spare parts.. Thought 6gigs of RAM would have been enough for only 1.5 tb

Kevin Horton · Apr 18, 2016

The official hardware requirements say a MINIMUM of 8 GB of RAM. You would need more if you wanted to run plugins, or if you had more than a minimal amount of storage. You are running less than the specified minimum amount of RAM, and you are trying to run a plugin, which consumes RAM. You could be running out of RAM.

Code:

Apr 17 15:27:38 freenas smartd[1972]: Device: /dev/ada2, FAILED SMART self-check. BACK UP DATA NOW!

Another possible issue - your log shows that one of your drives has failed the SMART self-check. I have no idea what impact that could have.

vicesig · Apr 19, 2016

Kevin Horton said:
The official hardware requirements say a MINIMUM of 8 GB of RAM. You would need more if you wanted to run plugins, or if you had more than a minimal amount of storage. You are running less than the specified minimum amount of RAM, and you are trying to run a plugin, which consumes RAM. You could be running out of RAM.

Code:
Apr 17 15:27:38 freenas smartd[1972]: Device: /dev/ada2, FAILED SMART self-check. BACK UP DATA NOW!

Another possible issue - your log shows that one of your drives has failed the SMART self-check. I have no idea what impact that could have.

I can try to squeeze into the motherboard another couple of gigs just for testing.
About SMART, yes, I know, the SMART fails but the HDD works. I put it in because even if it fails using ZFS I'm safe

vicesig · Apr 20, 2016

I finally got my answer: it was indeed the RAM. With 8gigs it works flawlessy.
Thanks to everybody for the help!

Kevin Horton · Apr 20, 2016

Thanks for updating us. Good luck with FreeNAS.

Important Announcement for the TrueNAS Community.

weird transmission problem

vicesig

Cadet

dlavigne

Guest

vicesig

Cadet

dlavigne

Guest

vicesig

Cadet

Kevin Horton

Guru

vicesig

Cadet

vicesig

Cadet

Kevin Horton

Guru

Similar threads

Important Announcement for the TrueNAS Community.

weird transmission problem

vicesig

Cadet

dlavigne

Guest

vicesig

Cadet

dlavigne

Guest

vicesig

Cadet

Kevin Horton

Guru

vicesig

Cadet

vicesig

Cadet

Kevin Horton

Guru

Important Announcement for the TrueNAS Community.

Related topics on forums.truenas.com for thread: "weird transmission problem"

Similar threads