root@truenas[~]# more /var/log/middlewared.log
[2022/01/02 12:51:48] (INFO) middlewared.__init__():799 - Starting TrueNAS-12.0- U7 middleware
/usr/local/lib/python3.9/site-packages/pyVmomi/Version.py:26: SyntaxWarning: "is " with a literal. Did you mean "=="?
if isLegacy or ns is "":
[2022/01/02 12:52:18] (INFO) middlewared.firstboot():1682 - Creating 'Initial-In stall' boot environment...
[2022/01/02 04:52:24] (DEBUG) middlewared.setup():1780 - Timezone set to America /Los_Angeles
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():111 - Creating new group 'ntpd'
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():111 - Creating new group 'builtin_administrators'
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():111 - Creating new group 'builtin_users'
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():111 - Creating new group 'builtin_guests'
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():245 - Creating new user 'ntpd'
[2022/01/02 04:52:27] (DEBUG) middlewared.setup():2832 - Default certificate for System created
[2022/01/02 04:52:27] (DEBUG) middlewared.setup():2847 - Certificate setup for S ystem complete
--More--(0%)...skipping...
[2022/01/02 12:51:48] (INFO) middlewared.__init__():799 - Starting TrueNAS-12.0-U7 middleware
/usr/local/lib/python3.9/site-packages/pyVmomi/Version.py:26: SyntaxWarning: "is" with a literal. Did you mean "=="?
if isLegacy or ns is "":
[2022/01/02 12:52:18] (INFO) middlewared.firstboot():1682 - Creating 'Initial-Install' boot environment...
[2022/01/02 04:52:24] (DEBUG) middlewared.setup():1780 - Timezone set to America/Los_Angeles
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():111 - Creating new group 'ntpd'
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():111 - Creating new group 'builtin_administrators'
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():111 - Creating new group 'builtin_users'
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():111 - Creating new group 'builtin_guests'
[2022/01/02 04:52:26] (INFO) UserService.sync_builtin():245 - Creating new user 'ntpd'
[2022/01/02 04:52:27] (DEBUG) middlewared.setup():2832 - Default certificate for System created
[2022/01/02 04:52:27] (DEBUG) middlewared.setup():2847 - Certificate setup for System complete
[2022/01/02 04:52:28] (INFO) middlewared.devd_listen():58 - devd connection established
[2022/01/02 04:52:28] (DEBUG) middlewared.__plugins_setup():915 - All plugins loaded
[2022/01/02 04:52:28] (DEBUG) middlewared.__initialize():1581 - Accepting connections
[2022/01/02 04:52:30] (INFO) DiskService.sync_all():95 - Found disks: {'ada5': {'name': 'ada5', 'ident': 'WD-WCC6Y1YV9X99', 'lunid': '50014ee20fe09027', 'serial': 'WD-WCC6Y1YV9X99'}, 'ada4': {'name': 'ada4', 'ident': '5YD7HZF2', 'lunid': '5000c50045611a81', 'serial': '5YD7HZF2'}, 'ada3': {'name': 'ada3', 'ident': '5YD4LZ3B', 'lunid': '5000c50039262c0a', 'serial': '5YD4LZ3B'}, 'ada2': {'name': 'ada2', 'ident': 'Z4Y4WH2G', 'lunid': '5000c5007a712dfe', 'serial': 'Z4Y4WH2G'}, 'ada1': {'name': 'ada1', 'ident': 'NWAET71299YP', 'lunid': None, 'serial': 'NWAET71299YP'}, 'ada0': {'name': 'ada0', 'ident': '19336A801516', 'lunid': '5001b448b8d36794', 'serial': '19336A801516'}}
[2022/01/02 04:52:31] (DEBUG) EtcService.generate():378 - No new changes for /etc/krb5.conf
[2022/01/02 04:53:32] (DEBUG) EtcService.generate():378 - No new changes for /etc/krb5.conf
[2022/01/02 04:53:33] (DEBUG) EtcService.generate():378 - No new changes for /etc/krb5.conf
[2022/01/02 04:53:35] (DEBUG) EtcService.generate():378 - No new changes for /etc/pam.d/sshd
[2022/01/02 04:53:35] (DEBUG) EtcService.generate():329 - mako:local/users.oath file removed.
[2022/01/02 04:53:35] (DEBUG) EtcService.generate():329 - mako:local/openvpn/server/openvpn_server.conf file removed.
[2022/01/02 04:53:35] (DEBUG) EtcService.generate():329 - mako:local/openvpn/client/openvpn_client.conf file removed.
[2022/01/02 04:53:35] (DEBUG) EtcService.generate():329 - mako:wireguard/wg0.conf file removed.
[2022/01/02 04:53:39] (WARNING) middlewared.plugins.service_.services.base_freebsd.freebsd_service():136 - Failed to forcestop collectd-daemon with code 1 with error 'collectd_daemon not running? (check /var/run/collectd-daemon.pid).\n'
[2022/01/02 04:53:39] (WARNING) middlewared.plugins.service_.services.base_freebsd.freebsd_service():138 - rrdcached forcestop failed with code 1: 'rrdcached not running? (check /var/run/rrdcached.pid).\n'
[2022/01/02 04:53:40] (DEBUG) EtcService.generate():378 - No new changes for /etc/local/smb4.conf
[2022/01/02 04:53:42] (DEBUG) EtcService.generate():378 - No new changes for /etc/local/collectd.conf
[2022/01/02 04:53:42] (DEBUG) EtcService.generate():378 - No new changes for /etc/security/pam_winbind.conf
[2022/01/02 04:53:42] (DEBUG) EtcService.generate():378 - No new changes for /etc/local/smbusername.map
[2022/01/02 04:53:43] (DEBUG) EtcService.generate():378 - No new changes for /etc/local/apache24/httpd.conf
[2022/01/02 04:53:43] (DEBUG) EtcService.generate():378 - No new changes for /etc/local/apache24/Includes/webdav.conf
[2022/01/02 04:53:43] (DEBUG) EtcService.generate():378 - No new changes for /etc/group
[2022/01/02 04:53:43] (DEBUG) EtcService.generate():378 - No new changes for /etc/local/proftpd.conf
[2022/01/02 04:53:43] (DEBUG) EtcService.generate():378 - No new changes for /etc/master.passwd
[2022/01/02 04:53:44] (DEBUG) middlewared.interface_pre_sync_hook():1564 - No HA hardware detected, skipping interfaces setup.
[2022/01/02 04:53:44] (INFO) InterfaceService.sync():1852 - Interfaces in database: NONE
[2022/01/02 04:53:45] (WARNING) SMBService.validate_groupmap_hwm():210 - TDB maintenace failed: a bytes-like object is required, not 'NoneType'
[2022/01/02 04:53:46] (DEBUG) EtcService.generate():378 - No new changes for /etc/local/smb4_share.conf
[2022/01/02 04:53:49] (DEBUG) EtcService.generate():378 - No new changes for /etc/dhclient.conf
[2022/01/02 04:53:50] (DEBUG) EtcService.generate():378 - No new changes for /etc/nsswitch.conf
[2022/01/02 04:53:50] (DEBUG) EtcService.generate():378 - No new changes for /etc/local/nslcd.conf
[2022/01/02 04:53:52] (DEBUG) EtcService.generate():378 - No new changes for /etc/krb5.conf
[2022/01/02 04:53:54] (DEBUG) EtcService.generate():378 - No new changes for /etc/krb5.conf
[2022/01/02 04:53:54] (DEBUG) EtcService.generate():378 - No new changes for /etc/krb5.conf
[2022/01/02 04:53:55] (INFO) middlewared.run():42 - Running migration middlewared.migration.0001_cloudsync_sftp_private_key_file
[2022/01/02 04:53:55] (INFO) middlewared.run():42 - Running migration middlewared.migration.0003_iscsi_vendor
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task alert.flush_alerts to run every 3600 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task alert.process_alerts to run every 60 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task certificate.renew_certs to run every 86400 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task certificateauthority.crl_generation to run every 86400 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task kerberos.keytab.check_updated_keytab to run every 3600 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task kmip.sync_keys to run every 86400 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task mail.send_mail_queue to run every 600 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task plugin.periodic_plugin_update to run every 86400 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task plugin.retrieve_versions_for_repos to run every 86400 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task pool.dataset.sync_db_keys to run every 86400 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task smb.sharesec.check_share_info_tdb to run every 3600 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task truecommand.health_check to run every 1800 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task zettarepl.flush_state to run every 3600 seconds
[2022/01/02 04:53:56] (DEBUG) middlewared._update_birthday():1574 - Waiting for clock sync to update system birthday
[2022/01/02 04:53:56] (ERROR) iocage.callback():67 - Cloning git repository
[2022/01/02 04:53:56] (DEBUG) middlewared._update_birthday_data():1559 - Updating birthday data