Installation from FreeNAS-9.10.1-U2.iso did not go well

Status
Not open for further replies.
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
Hi,

First of all, congratulation to FreeNAS team for the great software.
This is my first attempt to run and install FreeNAS on production environment as a simple CIFS server joining in our Domain.
At first I have consider reading the how to over there : https://forums.freenas.org/index.ph...directory-folder-file-user-permissions.20610/ which helped me from a lot of trouble. Freenas has been installed on an ESXi 6 configured with 8GB ram and 2x10GB disk, one disk for installation and one for Raid 0 for simpe cifs shareing without compression. Now After a successfully domain join, strange things start happening. After reboot the server is no longer accessible. I can see a few bunch of logs line from the Dashboard as below :

Code:
Oct 14 12:27:03 kalisto sc0: <System console> at flags 0x100 on isa0
Oct 14 12:27:03 kalisto sc0: VGA <16 virtual consoles, flags=0x300>
Oct 14 12:27:03 kalisto vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Oct 14 12:27:03 kalisto coretemp0: <CPU On-Die Thermal Sensors> on cpu0
Oct 14 12:27:03 kalisto coretemp0: Tj(target) value 0 does not seem right.
Oct 14 12:27:03 kalisto ZFS filesystem version: 5
Oct 14 12:27:03 kalisto ZFS storage pool version: features support (5000)
Oct 14 12:27:03 kalisto Timecounters tick every 1.000 msec
Oct 14 12:27:03 kalisto ipfw2 (+ipv6) initialized, divert enabled, nat enabled, default to accept, logging disabled
Oct 14 12:27:03 kalisto random: unblocking device.
Oct 14 12:27:03 kalisto da0 at mpt0 bus 0 scbus2 target 0 lun 0
Oct 14 12:27:03 kalisto da0: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:27:03 kalisto da0: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:27:03 kalisto da0: Command Queueing enabled
Oct 14 12:27:03 kalisto da0: 10240MB (20971520 512 byte sectors)
Oct 14 12:27:03 kalisto da0: quirks=0x40<RETRY_BUSY>
Oct 14 12:27:03 kalisto da1 at mpt0 bus 0 scbus2 target 1 lun 0
Oct 14 12:27:03 kalisto da1: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:27:03 kalisto da1: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:27:03 kalisto da1: Command Queueing enabled
Oct 14 12:27:03 kalisto da1: 10240MB (20971520 512 byte sectors)
Oct 14 12:27:03 kalisto da1: quirks=0x40<RETRY_BUSY>
Oct 14 12:27:03 kalisto cd0 at ata1 bus 0 scbus1 target 0 lun 0
Oct 14 12:27:03 kalisto cd0: <NECVMWar VMware IDE CDR10 1.00> Removable CD-ROM SCSI device
Oct 14 12:27:03 kalisto cd0: Serial Number 10000000000000000001
Oct 14 12:27:03 kalisto cd0: 33.300MB/s transfers (UDMA2, ATAPI 12bytes, PIO 65534bytes)
Oct 14 12:27:03 kalisto cd0: Attempt to query device size failed: NOT READY, Medium not present
Oct 14 12:27:03 kalisto cd0: quirks=0x40<RETRY_BUSY>
Oct 14 12:27:03 kalisto Timecounter "TSC-low" frequency 1197227500 Hz quality 1000
Oct 14 12:27:03 kalisto Trying to mount root from zfs:freenas-boot/ROOT/default []...
Oct 14 12:27:03 kalisto GEOM_RAID5: Module loaded, version 1.3.20140711.62 (rev f91e28e40bf7)
Oct 14 12:27:03 kalisto hwpmc: SOFT/16/64/0x67<INT,USR,SYS,REA,WRI> TSC/1/64/0x20<REA> IAP/4/48/0x3ff<INT,USR,SYS,EDG,THR,REA,WRI,INV,QUA,PRC> IAF/0/0/0x67<INT,USR,SYS,REA,WRI>
Oct 14 12:27:03 kalisto VMware memory control driver initialized
Oct 14 12:27:03 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:27:03 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:27:05 kalisto generate_krb5_conf.py: [common.pipesubr:66] Popen()ing: klist
Oct 14 12:27:05 kalisto generate_krb5_conf.py: [common.pipesubr:66] Popen()ing: /usr/bin/kinit --renewable --password-file=/tmp/tmpQCqmle kalistoservice@[REMOVED FOR POSTING]
Oct 14 12:27:05 kalisto root: /etc/rc: WARNING: failed to start watchdogd
Oct 14 12:27:06 kalisto GEOM_ELI: Device da1p1.eli created.
Oct 14 12:27:06 kalisto GEOM_ELI: Encryption: AES-XTS 128
Oct 14 12:27:06 kalisto GEOM_ELI:	 Crypto: hardware
Oct 14 12:27:06 kalisto ntpd[2486]: ntpd 4.2.8p8-a (1): Starting
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [generate_smb4_conf:1498] Unable to open /var/db/samba4/private/secrets.tdb: [Errno 2] No such file or directory
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: klist
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: mount
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Oct 14 12:15:37 kalisto generate_ldap_conf.py: [common.pipesubr:66] Popen()ing: klist
Oct 14 12:15:38 kalisto ActiveDirectory: /usr/local/bin/python /usr/local/www/freenasUI/middleware/notifier.py stop cifs
Oct 14 12:15:39 kalisto notifier: winbindd not running? (check /var/run/samba/winbindd.pid).
Oct 14 12:15:39 kalisto notifier: smbd not running? (check /var/run/samba/smbd.pid).
Oct 14 12:15:39 kalisto notifier: nmbd not running? (check /var/run/samba/nmbd.pid).
Oct 14 12:15:40 kalisto ActiveDirectory: /usr/sbin/service ix-kerberos quietstop
Oct 14 12:15:40 kalisto ActiveDirectory: /usr/sbin/service ix-nsswitch quietstop
Oct 14 12:15:40 kalisto ActiveDirectory: /usr/sbin/service ix-pam quietstop
Oct 14 12:15:40 kalisto ActiveDirectory: /usr/sbin/service ix-activedirectory forcestop
Oct 14 12:15:42 kalisto adtool: [common.pipesubr:66] Popen()ing: klist
Oct 14 12:15:43 kalisto ActiveDirectory: /usr/sbin/service ix-cache quietstop &
Oct 14 12:15:43 kalisto ActiveDirectory: /usr/sbin/service samba_server forcestop
Oct 14 12:15:44 kalisto ActiveDirectory: /usr/sbin/service ix-pre-samba start
Oct 14 12:15:45 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Oct 14 12:15:45 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: mount
Oct 14 12:15:45 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Oct 14 12:15:45 kalisto ActiveDirectory: /usr/sbin/service ix-kinit forcestop
Oct 14 12:15:45 kalisto ActiveDirectory: /usr/sbin/service ix-hostname quietstart
Oct 14 12:15:46 kalisto smbd: dnssd_clientstub ConnectToServer: connect()-> No of tries: 1
Oct 14 12:27:23 kalisto smbd: dnssd_clientstub ConnectToServer: connect()-> No of tries: 2
Oct 14 12:27:24 kalisto smbd: dnssd_clientstub ConnectToServer: connect()-> No of tries: 3
Oct 14 12:27:24 kalisto mDNSResponder: mDNSResponder (Engineering Build) (Oct  3 2016 06:58:50) starting
Oct 14 12:27:24 kalisto mDNSResponder:   8: Listening for incoming Unix Domain Socket client requests
Oct 14 12:27:24 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 12:27:24 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 12:27:24 kalisto mDNSResponder: CheckNATMappings: Failed to allocate port 5350 UDP multicast socket for PCP & NAT-PMP announcements
Oct 14 12:27:25 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 kalisto.local. (Addr) that's already in the list
Oct 14 12:27:25 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 27.1.1.10.in-addr.arpa. (PTR) that's already in the list
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $growfs_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $ is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $vmware_guest__enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $tcsd_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $stunnel_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $nut_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $nut_upslog_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $nut_upsmon_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $xenguest_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $snmptrapd_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $rrdcached_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $openvpn_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $mdnsresponderposix_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $kpropd_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $iohyve_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $htcacheclean_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $git_daemon_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $django_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $consul_alerts_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $consul_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $collectdmon_enable is not set properly - see rc.conf(5).
Oct 14 12:21:14 kalisto ntpd[2487]: frequency error -2028543 PPM exceeds tolerance 500 PPM
Oct 14 12:21:14 kalisto mDNSResponder: mDNSCoreReceive: mDNSPlatformRawTime went backwards by 711886 ticks; setting correction factor to 2304627488
Oct 14 12:30:57 kalisto manage.py: [common.pipesubr:66] Popen()ing: zfs list -H -o mountpoint,name


After a Second restart, the logs it seems different again

Code:
Oct 14 12:51:30 kalisto pcib34: <ACPI PCI-PCI bridge> at device 24.7 on pci0
Oct 14 12:51:30 kalisto pci34: <ACPI PCI bus> on pcib34
Oct 14 12:51:30 kalisto acpi_acad0: <AC Adapter> on acpi0
Oct 14 12:51:30 kalisto atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
Oct 14 12:51:30 kalisto atkbd0: <AT Keyboard> irq 1 on atkbdc0
Oct 14 12:51:30 kalisto kbd0 at atkbd0
Oct 14 12:51:30 kalisto atkbd0: [GIANT-LOCKED]
Oct 14 12:51:30 kalisto psm0: <PS/2 Mouse> irq 12 on atkbdc0
Oct 14 12:51:30 kalisto psm0: [GIANT-LOCKED]
Oct 14 12:51:30 kalisto psm0: model IntelliMouse, device ID 3
Oct 14 12:51:30 kalisto uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
Oct 14 12:51:30 kalisto uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0
Oct 14 12:51:30 kalisto orm0: <ISA Option ROMs> at iomem 0xc0000-0xc7fff,0xc8000-0xc9fff,0xca000-0xcafff,0xdc000-0xdffff,0xe0000-0xe7fff on isa0
Oct 14 12:51:30 kalisto sc0: <System console> at flags 0x100 on isa0
Oct 14 12:51:30 kalisto sc0: VGA <16 virtual consoles, flags=0x300>
Oct 14 12:51:30 kalisto vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Oct 14 12:51:30 kalisto coretemp0: <CPU On-Die Thermal Sensors> on cpu0
Oct 14 12:51:30 kalisto coretemp0: Tj(target) value 0 does not seem right.
Oct 14 12:51:30 kalisto ZFS filesystem version: 5
Oct 14 12:51:30 kalisto ZFS storage pool version: features support (5000)
Oct 14 12:51:30 kalisto Timecounters tick every 1.000 msec
Oct 14 12:51:30 kalisto ipfw2 (+ipv6) initialized, divert enabled, nat enabled, default to accept, logging disabled
Oct 14 12:51:30 kalisto random: unblocking device.
Oct 14 12:51:30 kalisto da0 at mpt0 bus 0 scbus2 target 0 lun 0
Oct 14 12:51:30 kalisto da0: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:51:30 kalisto da0: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:51:30 kalisto da0: Command Queueing enabled
Oct 14 12:51:30 kalisto da0: 10240MB (20971520 512 byte sectors)
Oct 14 12:51:30 kalisto da0: quirks=0x40<RETRY_BUSY>
Oct 14 12:51:30 kalisto da1 at mpt0 bus 0 scbus2 target 1 lun 0
Oct 14 12:51:30 kalisto da1: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:51:30 kalisto da1: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:51:30 kalisto da1: Command Queueing enabled
Oct 14 12:51:30 kalisto da1: 10240MB (20971520 512 byte sectors)
Oct 14 12:51:30 kalisto da1: quirks=0x40<RETRY_BUSY>
Oct 14 12:51:30 kalisto cd0 at ata1 bus 0 scbus1 target 0 lun 0
Oct 14 12:51:30 kalisto cd0: <NECVMWar VMware IDE CDR10 1.00> Removable CD-ROM SCSI device
Oct 14 12:51:30 kalisto cd0: Serial Number 10000000000000000001
Oct 14 12:51:30 kalisto cd0: 33.300MB/s transfers (UDMA2, ATAPI 12bytes, PIO 65534bytes)
Oct 14 12:51:30 kalisto cd0: Attempt to query device size failed: NOT READY, Medium not present
Oct 14 12:51:30 kalisto cd0: quirks=0x40<RETRY_BUSY>
Oct 14 12:51:30 kalisto Timecounter "TSC-low" frequency 1197227500 Hz quality 1000
Oct 14 12:51:30 kalisto Trying to mount root from zfs:freenas-boot/ROOT/default []...
Oct 14 12:51:30 kalisto GEOM_RAID5: Module loaded, version 1.3.20140711.62 (rev f91e28e40bf7)
Oct 14 12:51:30 kalisto hwpmc: SOFT/16/64/0x67<INT,USR,SYS,REA,WRI> TSC/1/64/0x20<REA> IAP/4/48/0x3ff<INT,USR,SYS,EDG,THR,REA,WRI,INV,QUA,PRC> IAF/0/0/0x67<INT,USR,SYS,REA,WRI>
Oct 14 12:51:30 kalisto VMware memory control driver initialized
Oct 14 12:51:30 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:51:30 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:51:30 kalisto root: /etc/rc: WARNING: failed to start watchdogd
Oct 14 12:51:31 kalisto GEOM_ELI: Device da1p1.eli created.
Oct 14 12:51:31 kalisto GEOM_ELI: Encryption: AES-XTS 128
Oct 14 12:51:31 kalisto GEOM_ELI:	 Crypto: hardware
Oct 14 12:51:31 kalisto ntpd[1723]: ntpd 4.2.8p8-a (1): Starting
Oct 14 12:51:33 kalisto generate_smb4_conf.py: [generate_smb4_conf:1498] Unable to open /var/db/samba4/private/secrets.tdb: [Errno 2] No such file or directory
Oct 14 12:51:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Oct 14 12:51:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: mount
Oct 14 12:51:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Oct 14 13:03:12 kalisto mDNSResponder: mDNSResponder (Engineering Build) (Oct  3 2016 06:58:50) starting
Oct 14 13:03:12 kalisto mDNSResponder:   8: Listening for incoming Unix Domain Socket client requests
Oct 14 13:03:12 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 13:03:12 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 13:03:12 kalisto mDNSResponder: CheckNATMappings: Failed to allocate port 5350 UDP multicast socket for PCP & NAT-PMP announcements
Oct 14 13:03:17 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 kalisto.local. (Addr) that's already in the list
Oct 14 13:03:17 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 27.1.1.10.in-addr.arpa. (PTR) that's already in the list


Just to be sure I have apply also the latest update from FreeNAS and now the booting is somehow different, please check below :

Code:
Oct 14 12:58:54 kalisto pcib33: <ACPI PCI-PCI bridge> at device 24.6 on pci0
Oct 14 12:58:54 kalisto pci33: <ACPI PCI bus> on pcib33
Oct 14 12:58:54 kalisto pcib34: <ACPI PCI-PCI bridge> at device 24.7 on pci0
Oct 14 12:58:54 kalisto pci34: <ACPI PCI bus> on pcib34
Oct 14 12:58:54 kalisto acpi_acad0: <AC Adapter> on acpi0
Oct 14 12:58:54 kalisto atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
Oct 14 12:58:54 kalisto atkbd0: <AT Keyboard> irq 1 on atkbdc0
Oct 14 12:58:54 kalisto kbd0 at atkbd0
Oct 14 12:58:54 kalisto atkbd0: [GIANT-LOCKED]
Oct 14 12:58:54 kalisto psm0: <PS/2 Mouse> irq 12 on atkbdc0
Oct 14 12:58:54 kalisto psm0: [GIANT-LOCKED]
Oct 14 12:58:54 kalisto psm0: model IntelliMouse, device ID 3
Oct 14 12:58:54 kalisto uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
Oct 14 12:58:54 kalisto uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0
Oct 14 12:58:54 kalisto orm0: <ISA Option ROMs> at iomem 0xc0000-0xc7fff,0xc8000-0xc9fff,0xca000-0xcafff,0xdc000-0xdffff,0xe0000-0xe7fff on isa0
Oct 14 12:58:54 kalisto sc0: <System console> at flags 0x100 on isa0
Oct 14 12:58:54 kalisto sc0: VGA <16 virtual consoles, flags=0x300>
Oct 14 12:58:54 kalisto vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Oct 14 12:58:54 kalisto coretemp0: <CPU On-Die Thermal Sensors> on cpu0
Oct 14 12:58:54 kalisto coretemp0: Tj(target) value 0 does not seem right.
Oct 14 12:58:54 kalisto ZFS filesystem version: 5
Oct 14 12:58:54 kalisto ZFS storage pool version: features support (5000)
Oct 14 12:58:54 kalisto Timecounters tick every 1.000 msec
Oct 14 12:58:54 kalisto ipfw2 (+ipv6) initialized, divert enabled, nat enabled, default to accept, logging disabled
Oct 14 12:58:54 kalisto random: unblocking device.
Oct 14 12:58:54 kalisto da0 at mpt0 bus 0 scbus2 target 0 lun 0
Oct 14 12:58:54 kalisto da0: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:58:54 kalisto da0: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:58:54 kalisto da0: Command Queueing enabled
Oct 14 12:58:54 kalisto da0: 10240MB (20971520 512 byte sectors)
Oct 14 12:58:54 kalisto da0: quirks=0x40<RETRY_BUSY>
Oct 14 12:58:54 kalisto da1 at mpt0 bus 0 scbus2 target 1 lun 0
Oct 14 12:58:54 kalisto da1: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:58:54 kalisto da1: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:58:54 kalisto da1: Command Queueing enabled
Oct 14 12:58:54 kalisto da1: 10240MB (20971520 512 byte sectors)
Oct 14 12:58:54 kalisto da1: quirks=0x40<RETRY_BUSY>
Oct 14 12:58:54 kalisto cd0 at ata1 bus 0 scbus1 target 0 lun 0
Oct 14 12:58:54 kalisto cd0: <NECVMWar VMware IDE CDR10 1.00> Removable CD-ROM SCSI device
Oct 14 12:58:54 kalisto cd0: Serial Number 10000000000000000001
Oct 14 12:58:54 kalisto cd0: 33.300MB/s transfers (UDMA2, ATAPI 12bytes, PIO 65534bytes)
Oct 14 12:58:54 kalisto cd0: Attempt to query device size failed: NOT READY, Medium not present
Oct 14 12:58:54 kalisto cd0: quirks=0x40<RETRY_BUSY>
Oct 14 12:58:54 kalisto Timecounter "TSC-low" frequency 1197227500 Hz quality 1000
Oct 14 12:58:54 kalisto Trying to mount root from zfs:freenas-boot/ROOT/9.10.1-U2 []...
Oct 14 12:58:54 kalisto GEOM_RAID5: Module loaded, version 1.3.20140711.62 (rev f91e28e40bf7)
Oct 14 12:58:54 kalisto hwpmc: SOFT/16/64/0x67<INT,USR,SYS,REA,WRI> TSC/1/64/0x20<REA> IAP/4/48/0x3ff<INT,USR,SYS,EDG,THR,REA,WRI,INV,QUA,PRC> IAF/0/0/0x67<INT,USR,SYS,REA,WRI>
Oct 14 12:58:54 kalisto VMware memory control driver initialized
Oct 14 12:58:54 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:58:54 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:58:55 kalisto root: /etc/rc: WARNING: failed to start watchdogd
Oct 14 12:58:56 kalisto GEOM_ELI: Device da1p1.eli created.
Oct 14 12:58:56 kalisto GEOM_ELI: Encryption: AES-XTS 128
Oct 14 12:58:56 kalisto GEOM_ELI:	 Crypto: hardware
Oct 14 12:58:56 kalisto ntpd[1723]: ntpd 4.2.8p8-a (1): Starting
Oct 14 12:58:58 kalisto generate_smb4_conf.py: [generate_smb4_conf:1498] Unable to open /var/db/samba4/private/secrets.tdb: [Errno 2] No such file or directory
Oct 14 12:58:58 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Oct 14 12:58:59 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: mount
Oct 14 12:58:59 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Oct 14 13:10:37 kalisto mDNSResponder: mDNSResponder (Engineering Build) (Oct  3 2016 06:58:50) starting
Oct 14 13:10:37 kalisto mDNSResponder:   8: Listening for incoming Unix Domain Socket client requests
Oct 14 13:10:37 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 13:10:37 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 13:10:37 kalisto mDNSResponder: CheckNATMappings: Failed to allocate port 5350 UDP multicast socket for PCP & NAT-PMP announcements
Oct 14 13:10:43 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 kalisto.local. (Addr) that's already in the list
Oct 14 13:10:43 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 27.1.1.10.in-addr.arpa. (PTR) that's already in the list


At the moment if I type \\kalisto from a workstation the response is "The file cannot be accessed by the system."

Can someone help me in order to investigate and solve the issue?

Thank you
 
Last edited:
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
Panagiotis G said:
Hi,

First of all, congratulation to FreeNAS team for the great software.
This is my first attempt to run and install FreeNAS on production environment as a simple CIFS server joining in our Domain.
At first I have consider reading the how to over there : https://forums.freenas.org/index.ph...directory-folder-file-user-permissions.20610/ which helped me from a lot of trouble. Freenas has been installed on an ESXi 6 configured with 8GB ram and 2x10GB disk, one disk for installation and one for Raid 0 for simpe cifs shareing without compression. Now After a successfully domain join, strange things start happening. After reboot the server is no longer accessible. I can see a few bunch of logs line from the Dashboard as below :

Code:
Oct 14 12:27:03 kalisto sc0: <System console> at flags 0x100 on isa0
Oct 14 12:27:03 kalisto sc0: VGA <16 virtual consoles, flags=0x300>
Oct 14 12:27:03 kalisto vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Oct 14 12:27:03 kalisto coretemp0: <CPU On-Die Thermal Sensors> on cpu0
Oct 14 12:27:03 kalisto coretemp0: Tj(target) value 0 does not seem right.
Oct 14 12:27:03 kalisto ZFS filesystem version: 5
Oct 14 12:27:03 kalisto ZFS storage pool version: features support (5000)
Oct 14 12:27:03 kalisto Timecounters tick every 1.000 msec
Oct 14 12:27:03 kalisto ipfw2 (+ipv6) initialized, divert enabled, nat enabled, default to accept, logging disabled
Oct 14 12:27:03 kalisto random: unblocking device.
Oct 14 12:27:03 kalisto da0 at mpt0 bus 0 scbus2 target 0 lun 0
Oct 14 12:27:03 kalisto da0: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:27:03 kalisto da0: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:27:03 kalisto da0: Command Queueing enabled
Oct 14 12:27:03 kalisto da0: 10240MB (20971520 512 byte sectors)
Oct 14 12:27:03 kalisto da0: quirks=0x40<RETRY_BUSY>
Oct 14 12:27:03 kalisto da1 at mpt0 bus 0 scbus2 target 1 lun 0
Oct 14 12:27:03 kalisto da1: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:27:03 kalisto da1: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:27:03 kalisto da1: Command Queueing enabled
Oct 14 12:27:03 kalisto da1: 10240MB (20971520 512 byte sectors)
Oct 14 12:27:03 kalisto da1: quirks=0x40<RETRY_BUSY>
Oct 14 12:27:03 kalisto cd0 at ata1 bus 0 scbus1 target 0 lun 0
Oct 14 12:27:03 kalisto cd0: <NECVMWar VMware IDE CDR10 1.00> Removable CD-ROM SCSI device
Oct 14 12:27:03 kalisto cd0: Serial Number 10000000000000000001
Oct 14 12:27:03 kalisto cd0: 33.300MB/s transfers (UDMA2, ATAPI 12bytes, PIO 65534bytes)
Oct 14 12:27:03 kalisto cd0: Attempt to query device size failed: NOT READY, Medium not present
Oct 14 12:27:03 kalisto cd0: quirks=0x40<RETRY_BUSY>
Oct 14 12:27:03 kalisto Timecounter "TSC-low" frequency 1197227500 Hz quality 1000
Oct 14 12:27:03 kalisto Trying to mount root from zfs:freenas-boot/ROOT/default []...
Oct 14 12:27:03 kalisto GEOM_RAID5: Module loaded, version 1.3.20140711.62 (rev f91e28e40bf7)
Oct 14 12:27:03 kalisto hwpmc: SOFT/16/64/0x67<INT,USR,SYS,REA,WRI> TSC/1/64/0x20<REA> IAP/4/48/0x3ff<INT,USR,SYS,EDG,THR,REA,WRI,INV,QUA,PRC> IAF/0/0/0x67<INT,USR,SYS,REA,WRI>
Oct 14 12:27:03 kalisto VMware memory control driver initialized
Oct 14 12:27:03 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:27:03 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:27:05 kalisto generate_krb5_conf.py: [common.pipesubr:66] Popen()ing: klist
Oct 14 12:27:05 kalisto generate_krb5_conf.py: [common.pipesubr:66] Popen()ing: /usr/bin/kinit --renewable --password-file=/tmp/tmpQCqmle kalistoservice@[REMOVED FOR POSTING]
Oct 14 12:27:05 kalisto root: /etc/rc: WARNING: failed to start watchdogd
Oct 14 12:27:06 kalisto GEOM_ELI: Device da1p1.eli created.
Oct 14 12:27:06 kalisto GEOM_ELI: Encryption: AES-XTS 128
Oct 14 12:27:06 kalisto GEOM_ELI:	 Crypto: hardware
Oct 14 12:27:06 kalisto ntpd[2486]: ntpd 4.2.8p8-a (1): Starting
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [generate_smb4_conf:1498] Unable to open /var/db/samba4/private/secrets.tdb: [Errno 2] No such file or directory
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: klist
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: mount
Oct 14 12:15:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Oct 14 12:15:37 kalisto generate_ldap_conf.py: [common.pipesubr:66] Popen()ing: klist
Oct 14 12:15:38 kalisto ActiveDirectory: /usr/local/bin/python /usr/local/www/freenasUI/middleware/notifier.py stop cifs
Oct 14 12:15:39 kalisto notifier: winbindd not running? (check /var/run/samba/winbindd.pid).
Oct 14 12:15:39 kalisto notifier: smbd not running? (check /var/run/samba/smbd.pid).
Oct 14 12:15:39 kalisto notifier: nmbd not running? (check /var/run/samba/nmbd.pid).
Oct 14 12:15:40 kalisto ActiveDirectory: /usr/sbin/service ix-kerberos quietstop
Oct 14 12:15:40 kalisto ActiveDirectory: /usr/sbin/service ix-nsswitch quietstop
Oct 14 12:15:40 kalisto ActiveDirectory: /usr/sbin/service ix-pam quietstop
Oct 14 12:15:40 kalisto ActiveDirectory: /usr/sbin/service ix-activedirectory forcestop
Oct 14 12:15:42 kalisto adtool: [common.pipesubr:66] Popen()ing: klist
Oct 14 12:15:43 kalisto ActiveDirectory: /usr/sbin/service ix-cache quietstop &
Oct 14 12:15:43 kalisto ActiveDirectory: /usr/sbin/service samba_server forcestop
Oct 14 12:15:44 kalisto ActiveDirectory: /usr/sbin/service ix-pre-samba start
Oct 14 12:15:45 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Oct 14 12:15:45 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: mount
Oct 14 12:15:45 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Oct 14 12:15:45 kalisto ActiveDirectory: /usr/sbin/service ix-kinit forcestop
Oct 14 12:15:45 kalisto ActiveDirectory: /usr/sbin/service ix-hostname quietstart
Oct 14 12:15:46 kalisto smbd: dnssd_clientstub ConnectToServer: connect()-> No of tries: 1
Oct 14 12:27:23 kalisto smbd: dnssd_clientstub ConnectToServer: connect()-> No of tries: 2
Oct 14 12:27:24 kalisto smbd: dnssd_clientstub ConnectToServer: connect()-> No of tries: 3
Oct 14 12:27:24 kalisto mDNSResponder: mDNSResponder (Engineering Build) (Oct  3 2016 06:58:50) starting
Oct 14 12:27:24 kalisto mDNSResponder:   8: Listening for incoming Unix Domain Socket client requests
Oct 14 12:27:24 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 12:27:24 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 12:27:24 kalisto mDNSResponder: CheckNATMappings: Failed to allocate port 5350 UDP multicast socket for PCP & NAT-PMP announcements
Oct 14 12:27:25 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 kalisto.local. (Addr) that's already in the list
Oct 14 12:27:25 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 27.1.1.10.in-addr.arpa. (PTR) that's already in the list
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $growfs_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $ is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $vmware_guest__enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $tcsd_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $stunnel_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $nut_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $nut_upslog_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $nut_upsmon_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $xenguest_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $snmptrapd_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $rrdcached_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $openvpn_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $mdnsresponderposix_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $kpropd_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $iohyve_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $htcacheclean_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $git_daemon_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $django_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $consul_alerts_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $consul_enable is not set properly - see rc.conf(5).
Oct 14 12:30:48 kalisto root: /usr/sbin/service: WARNING: $collectdmon_enable is not set properly - see rc.conf(5).
Oct 14 12:21:14 kalisto ntpd[2487]: frequency error -2028543 PPM exceeds tolerance 500 PPM
Oct 14 12:21:14 kalisto mDNSResponder: mDNSCoreReceive: mDNSPlatformRawTime went backwards by 711886 ticks; setting correction factor to 2304627488
Oct 14 12:30:57 kalisto manage.py: [common.pipesubr:66] Popen()ing: zfs list -H -o mountpoint,name


After a Second restart, the logs it seems different again

Code:
Oct 14 12:51:30 kalisto pcib34: <ACPI PCI-PCI bridge> at device 24.7 on pci0
Oct 14 12:51:30 kalisto pci34: <ACPI PCI bus> on pcib34
Oct 14 12:51:30 kalisto acpi_acad0: <AC Adapter> on acpi0
Oct 14 12:51:30 kalisto atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
Oct 14 12:51:30 kalisto atkbd0: <AT Keyboard> irq 1 on atkbdc0
Oct 14 12:51:30 kalisto kbd0 at atkbd0
Oct 14 12:51:30 kalisto atkbd0: [GIANT-LOCKED]
Oct 14 12:51:30 kalisto psm0: <PS/2 Mouse> irq 12 on atkbdc0
Oct 14 12:51:30 kalisto psm0: [GIANT-LOCKED]
Oct 14 12:51:30 kalisto psm0: model IntelliMouse, device ID 3
Oct 14 12:51:30 kalisto uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
Oct 14 12:51:30 kalisto uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0
Oct 14 12:51:30 kalisto orm0: <ISA Option ROMs> at iomem 0xc0000-0xc7fff,0xc8000-0xc9fff,0xca000-0xcafff,0xdc000-0xdffff,0xe0000-0xe7fff on isa0
Oct 14 12:51:30 kalisto sc0: <System console> at flags 0x100 on isa0
Oct 14 12:51:30 kalisto sc0: VGA <16 virtual consoles, flags=0x300>
Oct 14 12:51:30 kalisto vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Oct 14 12:51:30 kalisto coretemp0: <CPU On-Die Thermal Sensors> on cpu0
Oct 14 12:51:30 kalisto coretemp0: Tj(target) value 0 does not seem right.
Oct 14 12:51:30 kalisto ZFS filesystem version: 5
Oct 14 12:51:30 kalisto ZFS storage pool version: features support (5000)
Oct 14 12:51:30 kalisto Timecounters tick every 1.000 msec
Oct 14 12:51:30 kalisto ipfw2 (+ipv6) initialized, divert enabled, nat enabled, default to accept, logging disabled
Oct 14 12:51:30 kalisto random: unblocking device.
Oct 14 12:51:30 kalisto da0 at mpt0 bus 0 scbus2 target 0 lun 0
Oct 14 12:51:30 kalisto da0: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:51:30 kalisto da0: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:51:30 kalisto da0: Command Queueing enabled
Oct 14 12:51:30 kalisto da0: 10240MB (20971520 512 byte sectors)
Oct 14 12:51:30 kalisto da0: quirks=0x40<RETRY_BUSY>
Oct 14 12:51:30 kalisto da1 at mpt0 bus 0 scbus2 target 1 lun 0
Oct 14 12:51:30 kalisto da1: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:51:30 kalisto da1: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:51:30 kalisto da1: Command Queueing enabled
Oct 14 12:51:30 kalisto da1: 10240MB (20971520 512 byte sectors)
Oct 14 12:51:30 kalisto da1: quirks=0x40<RETRY_BUSY>
Oct 14 12:51:30 kalisto cd0 at ata1 bus 0 scbus1 target 0 lun 0
Oct 14 12:51:30 kalisto cd0: <NECVMWar VMware IDE CDR10 1.00> Removable CD-ROM SCSI device
Oct 14 12:51:30 kalisto cd0: Serial Number 10000000000000000001
Oct 14 12:51:30 kalisto cd0: 33.300MB/s transfers (UDMA2, ATAPI 12bytes, PIO 65534bytes)
Oct 14 12:51:30 kalisto cd0: Attempt to query device size failed: NOT READY, Medium not present
Oct 14 12:51:30 kalisto cd0: quirks=0x40<RETRY_BUSY>
Oct 14 12:51:30 kalisto Timecounter "TSC-low" frequency 1197227500 Hz quality 1000
Oct 14 12:51:30 kalisto Trying to mount root from zfs:freenas-boot/ROOT/default []...
Oct 14 12:51:30 kalisto GEOM_RAID5: Module loaded, version 1.3.20140711.62 (rev f91e28e40bf7)
Oct 14 12:51:30 kalisto hwpmc: SOFT/16/64/0x67<INT,USR,SYS,REA,WRI> TSC/1/64/0x20<REA> IAP/4/48/0x3ff<INT,USR,SYS,EDG,THR,REA,WRI,INV,QUA,PRC> IAF/0/0/0x67<INT,USR,SYS,REA,WRI>
Oct 14 12:51:30 kalisto VMware memory control driver initialized
Oct 14 12:51:30 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:51:30 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:51:30 kalisto root: /etc/rc: WARNING: failed to start watchdogd
Oct 14 12:51:31 kalisto GEOM_ELI: Device da1p1.eli created.
Oct 14 12:51:31 kalisto GEOM_ELI: Encryption: AES-XTS 128
Oct 14 12:51:31 kalisto GEOM_ELI:	 Crypto: hardware
Oct 14 12:51:31 kalisto ntpd[1723]: ntpd 4.2.8p8-a (1): Starting
Oct 14 12:51:33 kalisto generate_smb4_conf.py: [generate_smb4_conf:1498] Unable to open /var/db/samba4/private/secrets.tdb: [Errno 2] No such file or directory
Oct 14 12:51:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Oct 14 12:51:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: mount
Oct 14 12:51:33 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Oct 14 13:03:12 kalisto mDNSResponder: mDNSResponder (Engineering Build) (Oct  3 2016 06:58:50) starting
Oct 14 13:03:12 kalisto mDNSResponder:   8: Listening for incoming Unix Domain Socket client requests
Oct 14 13:03:12 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 13:03:12 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 13:03:12 kalisto mDNSResponder: CheckNATMappings: Failed to allocate port 5350 UDP multicast socket for PCP & NAT-PMP announcements
Oct 14 13:03:17 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 kalisto.local. (Addr) that's already in the list
Oct 14 13:03:17 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 27.1.1.10.in-addr.arpa. (PTR) that's already in the list


Just to be sure I have apply also the latest update from FreeNAS and now the booting is somehow different, please check below :

Code:
Oct 14 12:58:54 kalisto pcib33: <ACPI PCI-PCI bridge> at device 24.6 on pci0
Oct 14 12:58:54 kalisto pci33: <ACPI PCI bus> on pcib33
Oct 14 12:58:54 kalisto pcib34: <ACPI PCI-PCI bridge> at device 24.7 on pci0
Oct 14 12:58:54 kalisto pci34: <ACPI PCI bus> on pcib34
Oct 14 12:58:54 kalisto acpi_acad0: <AC Adapter> on acpi0
Oct 14 12:58:54 kalisto atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
Oct 14 12:58:54 kalisto atkbd0: <AT Keyboard> irq 1 on atkbdc0
Oct 14 12:58:54 kalisto kbd0 at atkbd0
Oct 14 12:58:54 kalisto atkbd0: [GIANT-LOCKED]
Oct 14 12:58:54 kalisto psm0: <PS/2 Mouse> irq 12 on atkbdc0
Oct 14 12:58:54 kalisto psm0: [GIANT-LOCKED]
Oct 14 12:58:54 kalisto psm0: model IntelliMouse, device ID 3
Oct 14 12:58:54 kalisto uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
Oct 14 12:58:54 kalisto uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0
Oct 14 12:58:54 kalisto orm0: <ISA Option ROMs> at iomem 0xc0000-0xc7fff,0xc8000-0xc9fff,0xca000-0xcafff,0xdc000-0xdffff,0xe0000-0xe7fff on isa0
Oct 14 12:58:54 kalisto sc0: <System console> at flags 0x100 on isa0
Oct 14 12:58:54 kalisto sc0: VGA <16 virtual consoles, flags=0x300>
Oct 14 12:58:54 kalisto vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Oct 14 12:58:54 kalisto coretemp0: <CPU On-Die Thermal Sensors> on cpu0
Oct 14 12:58:54 kalisto coretemp0: Tj(target) value 0 does not seem right.
Oct 14 12:58:54 kalisto ZFS filesystem version: 5
Oct 14 12:58:54 kalisto ZFS storage pool version: features support (5000)
Oct 14 12:58:54 kalisto Timecounters tick every 1.000 msec
Oct 14 12:58:54 kalisto ipfw2 (+ipv6) initialized, divert enabled, nat enabled, default to accept, logging disabled
Oct 14 12:58:54 kalisto random: unblocking device.
Oct 14 12:58:54 kalisto da0 at mpt0 bus 0 scbus2 target 0 lun 0
Oct 14 12:58:54 kalisto da0: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:58:54 kalisto da0: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:58:54 kalisto da0: Command Queueing enabled
Oct 14 12:58:54 kalisto da0: 10240MB (20971520 512 byte sectors)
Oct 14 12:58:54 kalisto da0: quirks=0x40<RETRY_BUSY>
Oct 14 12:58:54 kalisto da1 at mpt0 bus 0 scbus2 target 1 lun 0
Oct 14 12:58:54 kalisto da1: <VMware Virtual disk 1.0> Fixed Direct Access SCSI-2 device
Oct 14 12:58:54 kalisto da1: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
Oct 14 12:58:54 kalisto da1: Command Queueing enabled
Oct 14 12:58:54 kalisto da1: 10240MB (20971520 512 byte sectors)
Oct 14 12:58:54 kalisto da1: quirks=0x40<RETRY_BUSY>
Oct 14 12:58:54 kalisto cd0 at ata1 bus 0 scbus1 target 0 lun 0
Oct 14 12:58:54 kalisto cd0: <NECVMWar VMware IDE CDR10 1.00> Removable CD-ROM SCSI device
Oct 14 12:58:54 kalisto cd0: Serial Number 10000000000000000001
Oct 14 12:58:54 kalisto cd0: 33.300MB/s transfers (UDMA2, ATAPI 12bytes, PIO 65534bytes)
Oct 14 12:58:54 kalisto cd0: Attempt to query device size failed: NOT READY, Medium not present
Oct 14 12:58:54 kalisto cd0: quirks=0x40<RETRY_BUSY>
Oct 14 12:58:54 kalisto Timecounter "TSC-low" frequency 1197227500 Hz quality 1000
Oct 14 12:58:54 kalisto Trying to mount root from zfs:freenas-boot/ROOT/9.10.1-U2 []...
Oct 14 12:58:54 kalisto GEOM_RAID5: Module loaded, version 1.3.20140711.62 (rev f91e28e40bf7)
Oct 14 12:58:54 kalisto hwpmc: SOFT/16/64/0x67<INT,USR,SYS,REA,WRI> TSC/1/64/0x20<REA> IAP/4/48/0x3ff<INT,USR,SYS,EDG,THR,REA,WRI,INV,QUA,PRC> IAF/0/0/0x67<INT,USR,SYS,REA,WRI>
Oct 14 12:58:54 kalisto VMware memory control driver initialized
Oct 14 12:58:54 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:58:54 kalisto kernel: vmx0: link state changed to UP
Oct 14 12:58:55 kalisto root: /etc/rc: WARNING: failed to start watchdogd
Oct 14 12:58:56 kalisto GEOM_ELI: Device da1p1.eli created.
Oct 14 12:58:56 kalisto GEOM_ELI: Encryption: AES-XTS 128
Oct 14 12:58:56 kalisto GEOM_ELI:	 Crypto: hardware
Oct 14 12:58:56 kalisto ntpd[1723]: ntpd 4.2.8p8-a (1): Starting
Oct 14 12:58:58 kalisto generate_smb4_conf.py: [generate_smb4_conf:1498] Unable to open /var/db/samba4/private/secrets.tdb: [Errno 2] No such file or directory
Oct 14 12:58:58 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
Oct 14 12:58:59 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: mount
Oct 14 12:58:59 kalisto generate_smb4_conf.py: [common.pipesubr:66] Popen()ing: /usr/local/bin/net -d 0 getlocalsid
Oct 14 13:10:37 kalisto mDNSResponder: mDNSResponder (Engineering Build) (Oct  3 2016 06:58:50) starting
Oct 14 13:10:37 kalisto mDNSResponder:   8: Listening for incoming Unix Domain Socket client requests
Oct 14 13:10:37 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 13:10:37 kalisto mDNSResponder: mDNS_AddDNSServer: Lock not held! mDNS_busy (0) mDNS_reentrancy (0)
Oct 14 13:10:37 kalisto mDNSResponder: CheckNATMappings: Failed to allocate port 5350 UDP multicast socket for PCP & NAT-PMP announcements
Oct 14 13:10:43 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801406D60 kalisto.local. (Addr) that's already in the list
Oct 14 13:10:43 kalisto mDNSResponder: mDNS_Register_internal: ERROR!! Tried to register AuthRecord 0000000801407180 27.1.1.10.in-addr.arpa. (PTR) that's already in the list


At the moment if I type \\kalisto from a workstation the response is "The file cannot be accessed by the system."

Can someone help me in order to investigate and solve the issue?

Thank you
Click to expand...

Ok, after a few restarts, I figured out that after the system restart you cannot see the shares directly and also the logs fills as below :

Code:
  SPNEGO(gse_krb5) NEG_TOKEN_INIT failed: NT_STATUS_LOGON_FAILURE
[2016/10/14 14:44:26.434702,  1] ../source3/librpc/crypto/gse.c:497(gse_get_server_auth_token)
  gss_accept_sec_context failed with [ Miscellaneous failure (see text): Failed to find cifs/kalisto.[REMOVED FOR POSTING]@[REMOVED FOR POSTING](kvno 22) in keytab MEMORY:cifs_srv_keytab (arcfour-hmac-md5)]
[2016/10/14 14:44:26.434791,  1] ../auth/gensec/spnego.c:541(gensec_spnego_parse_negTokenInit)
  SPNEGO(gse_krb5) NEG_TOKEN_INIT failed: NT_STATUS_LOGON_FAILURE
[2016/10/14 14:44:35.511794,  1] ../source3/librpc/crypto/gse.c:497(gse_get_server_auth_token)
  gss_accept_sec_context failed with [ Miscellaneous failure (see text): Failed to find cifs/kalisto.[REMOVED FOR POSTING]@[REMOVED FOR POSTING](kvno 22) in keytab MEMORY:cifs_srv_keytab (arcfour-hmac-md5)]
[2016/10/14 14:44:35.512041,  1] ../auth/gensec/spnego.c:541(gensec_spnego_parse_negTokenInit)
  SPNEGO(gse_krb5) NEG_TOKEN_INIT failed: NT_STATUS_LOGON_FAILURE


But after a few minutes I was trying to access my share files //kalisto without error on log.smbd . Instead I saw the below :

Code:
[2016/10/14 14:38:06.126695,  1] ../source3/printing/printer_list.c:234(printer_list_get_last_refresh)
  Failed to fetch record!
[2016/10/14 14:38:06.126912,  1] ../source3/smbd/server_reload.c:69(delete_and_reload_printers)
  pcap cache not loaded
[2016/10/14 14:38:06.275995,  1] ../source3/printing/printer_list.c:234(printer_list_get_last_refresh)
  Failed to fetch record!
[2016/10/14 14:38:06.276139,  1] ../source3/smbd/server_reload.c:69(delete_and_reload_printers)
  pcap cache not loaded


All I wan't to know is that ok? Will the system remain stable ? or is there anything I need to do in order that my system does not have any issue

Thank you
 
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
Panagiotis G said:
Ok, after a few restarts, I figured out that after the system restart you cannot see the shares directly and also the logs fills as below :

Code:
  SPNEGO(gse_krb5) NEG_TOKEN_INIT failed: NT_STATUS_LOGON_FAILURE
[2016/10/14 14:44:26.434702,  1] ../source3/librpc/crypto/gse.c:497(gse_get_server_auth_token)
  gss_accept_sec_context failed with [ Miscellaneous failure (see text): Failed to find cifs/kalisto.[REMOVED FOR POSTING]@[REMOVED FOR POSTING](kvno 22) in keytab MEMORY:cifs_srv_keytab (arcfour-hmac-md5)]
[2016/10/14 14:44:26.434791,  1] ../auth/gensec/spnego.c:541(gensec_spnego_parse_negTokenInit)
  SPNEGO(gse_krb5) NEG_TOKEN_INIT failed: NT_STATUS_LOGON_FAILURE
[2016/10/14 14:44:35.511794,  1] ../source3/librpc/crypto/gse.c:497(gse_get_server_auth_token)
  gss_accept_sec_context failed with [ Miscellaneous failure (see text): Failed to find cifs/kalisto.[REMOVED FOR POSTING]@[REMOVED FOR POSTING](kvno 22) in keytab MEMORY:cifs_srv_keytab (arcfour-hmac-md5)]
[2016/10/14 14:44:35.512041,  1] ../auth/gensec/spnego.c:541(gensec_spnego_parse_negTokenInit)
  SPNEGO(gse_krb5) NEG_TOKEN_INIT failed: NT_STATUS_LOGON_FAILURE


But after a few minutes I was trying to access my share files //kalisto without error on log.smbd . Instead I saw the below :

Code:
[2016/10/14 14:38:06.126695,  1] ../source3/printing/printer_list.c:234(printer_list_get_last_refresh)
  Failed to fetch record!
[2016/10/14 14:38:06.126912,  1] ../source3/smbd/server_reload.c:69(delete_and_reload_printers)
  pcap cache not loaded
[2016/10/14 14:38:06.275995,  1] ../source3/printing/printer_list.c:234(printer_list_get_last_refresh)
  Failed to fetch record!
[2016/10/14 14:38:06.276139,  1] ../source3/smbd/server_reload.c:69(delete_and_reload_printers)
  pcap cache not loaded


All I wan't to know is that ok? Will the system remain stable ? or is there anything I need to do in order that my system does not have any issue

Thank you
Click to expand...

Found the issue. I noticed after system reboot that date was not correct. Despite the fact that I had use my DC ip for NTP the ntp wasn't update the time correctly. I wans't able to trace the issue since ntp is not logged somewhere on syslog.conf. Anyway, the issue is fixed if you wait a couple of minutes, the ntp refresh itself then somehow the time is auto-adjusted. If you logged in via SSH and do service ntpd stop && service ntpd start then the time is changing to the correct time and finaly you can access the share path from your network. Maybe someone can en-light me more?
 
SweetAndLow

SweetAndLow

Sweet'NASty
Joined
Nov 6, 2013
Messages
6,421
FreeNAS version and hardware specs? I have a feeling you haven't read any of the best practices because you mention raid and turning off compression. Both of which are very bad choices.

Sent from my Nexus 5X using Tapatalk
 
Last edited by a moderator:
Ericloewe

Ericloewe

Server Wrangler
Moderator
Joined
Feb 15, 2014
Messages
20,194
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
SweetAndLow said:
FreeNAS version and hardware specs? I have a feeling you haven't read any of the best practices because you mention raid and turning off compression. Both of which are very bad choices.

Sent from my Nexus 5X using Tapatalk
Click to expand...

Ok let's clarify. I have turned off the compression and of course without raid but Stripe. From my knowledge Compression leverage high cpu. Also this is not physical machine but virtualized, all I wan't is a simple share directory with 1TB data nothing else. Why should I consider Raid-1 since my vmdk file resist on Vmware with High Availability and RAID10 Hardware NAS Server ?
 
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
Ericloewe said:
That's awfully low for an AD member.
Click to expand...

Can you please provide more details? FreeNAS automatically aquire 2GB Ram from the total of 8GB. Since I do not use any raid1 or high consumer raid level on a single vmdk file and with compression OFF why should I consider more ram ? Also please let me know if you believe that the RAM has to do with the issue I have with NTP and timing ? since the server is starting without Synced NTP time.

Also please accept the below link which user mav@ accept as safe ram of 8GB
https://forums.freenas.org/index.php?threads/does-freenas-really-need-8gb-of-ram.38685/
 
Ericloewe

Ericloewe

Server Wrangler
Moderator
Joined
Feb 15, 2014
Messages
20,194
Panagiotis G said:
Also please accept the below link which user mav@ accept as safe ram of 8GB
Click to expand...
It's the bare minimum. Additionally, the FreeNAS manual specifically states that AD requires extra RAM.
http://doc.freenas.org/9.10/intro.html#ram
Panagiotis G said:
Why should I consider Raid-1 since my vmdk file resist on Vmware with High Availability and RAID10 Hardware NAS Server ?
Click to expand...
Why would you want to use FreeNAS under those conditions? That's like buying a 24-core Xeon that'll be stuck running in Real Mode, executing DOS 5.x.
Panagiotis G said:
Also please let me know if you believe that the RAM has to do with the issue I have with NTP and timing ? since the server is starting without Synced NTP time.
Click to expand...
Stranger things have happened due to insufficient RAM. RAM pressure -> memory manager swaps out ntpd -> time-sensitive tasks start failing because ntpd wasn't fast enough and your system has a crap real-time clock (or whatever is going on).

But your immediate issue aside, 8GB of RAM is absolutely puny for FreeNAS in any sort of productive environment and performance will suffer.
 
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
Ericloewe said:
It's the bare minimum. Additionally, the FreeNAS manual specifically states that AD requires extra RAM.
http://doc.freenas.org/9.10/intro.html#ram

Why would you want to use FreeNAS under those conditions? That's like buying a 24-core Xeon that'll be stuck running in Real Mode, executing DOS 5.x.

Stranger things have happened due to insufficient RAM. RAM pressure -> memory manager swaps out ntpd -> time-sensitive tasks start failing because ntpd wasn't fast enough and your system has a crap real-time clock (or whatever is going on).

But your immediate issue aside, 8GB of RAM is absolutely puny for FreeNAS in any sort of productive environment and performance will suffer.
Click to expand...

Hi again,

I'd like to use FreeNAS because I do not wan't to setup a Windows Server OS for just a filesharing path. Also FreeNAS has been designed to be light as much as user decided on what technologies to use. So are you telling me that a simple Samba4 Share path would need additional RAM ? Ok no problem I have tested with 16GB of ram but the issue remain after FreeNAS restarted. Let me know if you have other suggestion instead of using FreeNAS because is RAM hungry or do you believe it's using resources aggressively. Also maybe you didn't understand me. A production env to production env might be different per situtation. Some they need 100TB FreeNAS installation with RAID6 or RAID10, some others they just need a simple Folder Share join to a domain controller using only a few MBytes (900MB) with Simple single volume as raid0 stripping without RAID level. If you believe that FreeNAS is not inteed for my situation for a simple project that I would like to share on my network, then I will accept your opinion and I will move on to an other Open Source project, but if you believe that FreeNAS is the ideal solution in my case installing to my VMware ESXi servers and running as Virtualized then I will happy to accept your comments and your adjustments for improving my setup.

Thank you
 
Ericloewe

Ericloewe

Server Wrangler
Moderator
Joined
Feb 15, 2014
Messages
20,194
Panagiotis G said:
Also FreeNAS has been designed to be light
Click to expand...
No. Whatever you've been reading, it's wrong. FreeNAS is designed for mid-to-large systems.
Panagiotis G said:
if you believe that FreeNAS is the ideal solution in my case
Click to expand...
It's definitely not ideal - your use case is very far away from what FreeNAS is meant for. You'll probably want something closer to a Linux server with a traditional filesystem.
 
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
Ericloewe said:
No. Whatever you've been reading, it's wrong. FreeNAS is designed for mid-to-large systems.

It's definitely not ideal - your use case is very far away from what FreeNAS is meant for. You'll probably want something closer to a Linux server with a traditional filesystem.
Click to expand...

Thank you, do you consider any other simple solution based on Open Source with Active Directory implement on it? Maybe Nas4Free ? Or any other linux / bsd with Web Driven interface?
 
Ericloewe

Ericloewe

Server Wrangler
Moderator
Joined
Feb 15, 2014
Messages
20,194
pirateghost

pirateghost

Unintelligible Geek
Joined
Feb 29, 2012
Messages
4,219
I don't understand why you wouldn't just use a Windows server for serving files to a Windows domain, and ease of management with Windows permissions....seems like you're trying to fit a square peg in a round hole.
 
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
pirateghost said:
I don't understand why you wouldn't just use a Windows server for serving files to a Windows domain, and ease of management with Windows permissions....seems like you're trying to fit a square peg in a round hole.
Click to expand...

Windows License over VM is not cheap. If you wan't to setup 4 diff machines for different share folders then totally is not cheap :) If you can do it with a free OpenSource and well supported project, why not go for it? FreeNAS was built in mind as light as it can, started with 2GB ram, then 4GB and finaly with 8GB from the time they decided to go native with ZFS. Yes, now it's a huge project for someone who wan't to replace their entire Hardware SAN/NAS with a cheap solution like FreeNAS and of course TrueNAS. What something started as simple idea, ended to be like octopus. Eg, do you believe that such serious project FreeNAS which it's based feature of advanced Raid functionality because of ZFS will someone bother for extra plugins on which only HOME users will interest? Only for entertainment purpose. Anyway, still I don't understand why it's so difficult to support the FreeNAS joinin as a simple AD Member and then fetch the user credentials and setup a simple share. If you don't need the rest of the functionalities you just disabled them and everything is fine.
 
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
pirateghost said:
I don't understand why you wouldn't just use a Windows server for serving files to a Windows domain, and ease of management with Windows permissions....seems like you're trying to fit a square peg in a round hole.
Click to expand...

Just straight also for your question, we intend to use FreeNAS as sync over Veeam off-site location for DR Purpose. Our File Server is taking a few TBs and that's a small problem. So we decided to just setup a spare share instances for that purpose which takes a few MBs and we can manage the availability instantly.
 
pirateghost

pirateghost

Unintelligible Geek
Joined
Feb 29, 2012
Messages
4,219
Panagiotis G said:
Windows License over VM is not cheap. If you wan't to setup 4 diff machines for different share folders then totally is not cheap :) If you can do it with a free OpenSource and well supported project, why not go for it? FreeNAS was built in mind as light as it can, started with 2GB ram, then 4GB and finaly with 8GB from the time they decided to go native with ZFS. Yes, now it's a huge project for someone who wan't to replace their entire Hardware SAN/NAS with a cheap solution like FreeNAS and of course TrueNAS. What something started as simple idea, ended to be like octopus. Eg, do you believe that such serious project FreeNAS which it's based feature of advanced Raid functionality because of ZFS will someone bother for extra plugins on which only HOME users will interest? Only for entertainment purpose. Anyway, still I don't understand why it's so difficult to support the FreeNAS joinin as a simple AD Member and then fetch the user credentials and setup a simple share. If you don't need the rest of the functionalities you just disabled them and everything is fine.
Click to expand...

Half of what you have said sounds like gibberish.

Why would you need 4 different machines for different shares?

As an enterprise administrator, I know how much Windows licenses are. I also know how much my time is worth and fighting an open source software to work properly with AD is a nightmare and time consuming. Especially when it's something as simple as file shares. I have never seen FreeNAS or any other "NAS OS" work flawlessly with AD. Period. Too much time spent troubleshooting, too much time spent managing.

And yes, I believe FreeNAS is appropriate for HOME use. If in a business environment, you should be using truenas.

Maybe you don't actually know the history of FreeNAS...but the ixsystems version (post version 0.7), was NOT for "repurposing old desktops", like was the case with the original. Completely different projects and goals. I don't see how FreeNAS "became an octopus".
 
P

Panagiotis G

Dabbler
Joined
Oct 14, 2016
Messages
10
pirateghost said:
Half of what you have said sounds like gibberish.

Why would you need 4 different machines for different shares?

As an enterprise administrator, I know how much Windows licenses are. I also know how much my time is worth and fighting an open source software to work properly with AD is a nightmare and time consuming. Especially when it's something as simple as file shares. I have never seen FreeNAS or any other "NAS OS" work flawlessly with AD. Period. Too much time spent troubleshooting, too much time spent managing.

And yes, I believe FreeNAS is appropriate for HOME use. If in a business environment, you should be using truenas.

Maybe you don't actually know the history of FreeNAS...but the ixsystems version (post version 0.7), was NOT for "repurposing old desktops", like was the case with the original. Completely different projects and goals. I don't see how FreeNAS "became an octopus".
Click to expand...


Thank you for your time and your post. Although I started a post regarding a simple issue for FreeNAS and ended by somwthing like FreeNAS is not for your purpose go for Windows machine :) Nice. Anyway I solved the issue anyway and now everything running smooth.

Thank you anyway
 
Status
Not open for further replies.

Similar threads

zoid009
  • Locked
FreeNAS-9.2.1.7 настройка\подключение к active directory
2 3
Replies
54
Views
12K
zoid009
zoid009
A
  • Locked
Cannot Join AD
Replies
3
Views
1K
m0nkey_
m0nkey_
E
  • Locked
Directory Services not starting after upgrade to 9.2.1.8
Replies
19
Views
4K
nesq
N
S
  • Locked
Directory Services could not be started
Replies
1
Views
1K
dlavigne
D
K
  • Locked
UPS Phasak Help
Replies
3
Views
3K
Kry0h
K
Top