[2022/10/06 07:42:52] (INFO) middlewared.__init__():799 - Starting TrueNAS-13.0-U2 middleware [2022/10/06 00:42:55] (DEBUG) middlewared.setup():1717 - Timezone set to America/Los_Angeles [2022/10/06 00:42:58] (DEBUG) middlewared.setup():2853 - Certificate setup for System complete [2022/10/06 00:42:58] (INFO) middlewared.devd_listen():58 - devd connection established [2022/10/06 00:42:58] (DEBUG) middlewared.__plugins_setup():915 - All plugins loaded [2022/10/06 00:42:58] (DEBUG) middlewared.__initialize():1629 - Accepting connections [2022/10/06 00:42:58] (INFO) DiskService.log_disk_info():123 - Found disks: {'da1': {'name': 'da1', 'ident': '2YJ2XJ3D ', 'lunid': '5000cca273dd82cf', 'serial': '2YJ2XJ3D '}, 'da0': {'name': 'da0', 'ident': '2YJD2H6D ', 'lunid': '5000cca273e1accf', 'serial': '2YJD2H6D '}, 'da2': {'name': 'da2', 'ident': '2YJDWHLD ', 'lunid': '5000cca273e20ab4', 'serial': '2YJDWHLD '}, 'da3': {'name': 'da3', 'ident': '2YJD81KD ', 'lunid': '5000cca273e1c1ae', 'serial': '2YJD81KD '}, 'ada0': {'name': 'ada0', 'ident': '', 'lunid': None, 'serial': ''}} [2022/10/06 00:42:59] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 00:42:59] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 00:42:59] (DEBUG) EtcService.generate():445 - No new changes for /etc/hosts [2022/10/06 00:42:59] (DEBUG) EtcService.generate():445 - No new changes for /etc/pam.d/sshd [2022/10/06 00:42:59] (DEBUG) EtcService.generate():429 - mako:local/users.oath file removed. [2022/10/06 00:43:00] (DEBUG) EtcService.generate():429 - mako:local/openvpn/server/openvpn_server.conf file removed. [2022/10/06 00:43:00] (DEBUG) EtcService.generate():429 - mako:local/openvpn/client/openvpn_client.conf file removed. [2022/10/06 00:43:00] (DEBUG) EtcService.generate():429 - mako:wireguard/wg0.conf file removed. [2022/10/06 00:43:04] (DEBUG) middlewared.dumpdev_configure():24 - Configured "/dev/da1p1" device for crash dumps. [2022/10/06 00:43:06] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/smb4.conf [2022/10/06 00:43:06] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/smbusername.map [2022/10/06 00:43:06] (DEBUG) EtcService.generate():445 - No new changes for /etc/group [2022/10/06 00:43:06] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/sudoers [2022/10/06 00:43:06] (DEBUG) EtcService.generate():445 - No new changes for /etc/mail/aliases [2022/10/06 00:43:06] (DEBUG) EtcService.generate():445 - No new changes for /etc/security/pam_winbind.conf [2022/10/06 00:43:07] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/apache24/httpd.conf [2022/10/06 00:43:07] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/apache24/Includes/webdav.conf [2022/10/06 00:43:07] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/proftpd.conf [2022/10/06 00:43:07] (INFO) ZFSSnapshot.do_create():1019 - Snapshot taken: Pool/.system/samba4@wbc-1665042186 [2022/10/06 00:43:07] (DEBUG) middlewared.interface_pre_sync_hook():1567 - No HA hardware detected, skipping interfaces setup. [2022/10/06 00:43:07] (DEBUG) InterfaceService.configure():154 - xn0: adding 192.168.1.10/255.255.255.0 [2022/10/06 00:43:07] (INFO) InterfaceService.sync():1955 - Interfaces in database: xn0 [2022/10/06 00:43:07] (DEBUG) EtcService.generate():445 - No new changes for /etc/dhclient.conf [2022/10/06 00:43:07] (INFO) RouteService.sync():2155 - Adding IPv4 default route to 192.168.1.1 [2022/10/06 00:43:08] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/smb4_share.conf [2022/10/06 00:43:08] (DEBUG) SharingSMBService.netconf():59 - netconf failure for command [['net', 'conf', 'addshare', 'Storage', '/mnt/Pool/General', 'writeable=y', 'guest_ok=N']] stdout: [2022/10/06 00:43:08] (WARNING) SharingSMBService.sync_registry():1304 - Failed to add SMB share [storage] while synchronizing registry config Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb.py", line 1302, in sync_registry await self.middleware.call('sharing.smb.reg_addshare', share_conf[0]) File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1278, in call return await self._call( File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1235, in _call return await methodobj(*prepared_call.args) File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb_/registry.py", line 76, in reg_addshare await self.netconf( File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb_/registry.py", line 61, in netconf raise CallError( middlewared.service_exception.CallError: [EFAULT] net conf addshare [Storage] failed with error: ERROR: share Storage already exists. [2022/10/06 00:43:08] (DEBUG) SharingSMBService.netconf():59 - netconf failure for command [['net', 'conf', 'addshare', 'General', '/mnt/Pool/General', 'writeable=y', 'guest_ok=N']] stdout: [2022/10/06 00:43:08] (WARNING) SharingSMBService.sync_registry():1304 - Failed to add SMB share [general] while synchronizing registry config Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb.py", line 1302, in sync_registry await self.middleware.call('sharing.smb.reg_addshare', share_conf[0]) File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1278, in call return await self._call( File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1235, in _call return await methodobj(*prepared_call.args) File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb_/registry.py", line 76, in reg_addshare await self.netconf( File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb_/registry.py", line 61, in netconf raise CallError( middlewared.service_exception.CallError: [EFAULT] net conf addshare [General] failed with error: ERROR: share General already exists. [2022/10/06 00:43:08] (DEBUG) EtcService.generate():445 - No new changes for /etc/nsswitch.conf [2022/10/06 00:43:08] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/nslcd.conf [2022/10/06 00:43:38] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 00:43:38] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task alert.flush_alerts to run every 3600 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task alert.process_alerts to run every 60 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task certificate.renew_certs to run every 86400 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task certificateauthority.crl_generation to run every 86400 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task hardware.events.retrieve_logs to run every 86400 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task kerberos.keytab.check_updated_keytab to run every 3600 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task kmip.sync_keys to run every 86400 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task mail.send_mail_queue to run every 600 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task plugin.periodic_plugin_update to run every 86400 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task plugin.retrieve_versions_for_repos to run every 86400 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task pool.dataset.sync_db_keys to run every 86400 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task service.check_deprecated_services to run every 3600 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task smb.sharesec.check_share_info_tdb to run every 3600 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task truecommand.health_check to run every 1800 seconds [2022/10/06 00:43:39] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task zettarepl.flush_state to run every 3600 seconds [2022/10/06 00:43:39] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/avahi/avahi-daemon.conf [2022/10/06 00:43:39] (DEBUG) JailService.start_on_boot():1656 - Starting jails on boot: PENDING [2022/10/06 00:43:39] (DEBUG) JailService.start_on_boot():1658 - Starting jails on boot: SUCCESS [2022/10/06 00:43:42] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/wsdd.conf [2022/10/06 00:43:50] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 00:43:50] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 00:43:50] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 00:43:50] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): download.truenas.com:80 [2022/10/06 00:43:50] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): download.truenas.com:80 [2022/10/06 00:44:00] (DEBUG) urllib3.connectionpool._make_request():452 - http://download.truenas.com:80 "GET /plugins/iconik/stable/12.3-RELEASE/packagesite.txz HTTP/1.1" 200 62924 [2022/10/06 00:44:01] (DEBUG) urllib3.connectionpool._make_request():452 - http://download.truenas.com:80 "GET /plugins/asigra/stable/12.3-RELEASE/packagesite.txz HTTP/1.1" 200 16964 [2022/10/06 00:44:01] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/latest/packagesite.txz HTTP/1.1" 200 6696220 [2022/10/06 00:44:01] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/latest/packagesite.txz HTTP/1.1" 200 6696220 [2022/10/06 00:44:01] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/quarterly/packagesite.txz HTTP/1.1" 200 6695652 [2022/10/06 00:44:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 00:44:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 00:44:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 00:44:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 00:44:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 00:44:11] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/latest/packagesite.txz HTTP/1.1" 200 6696220 [2022/10/06 00:44:11] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:12:amd64/quarterly/packagesite.txz HTTP/1.1" 200 6506544 [2022/10/06 00:44:11] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:12:amd64/latest/packagesite.txz HTTP/1.1" 200 6708016 [2022/10/06 00:44:11] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/quarterly/packagesite.txz HTTP/1.1" 200 6695652 [2022/10/06 00:44:11] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/latest/packagesite.txz HTTP/1.1" 200 6696220 [2022/10/06 01:13:39] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/ssh/sshd_config [2022/10/06 01:13:42] (WARNING) middlewared._loop_monitor_thread():1538 - Task seems blocked: File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/etc.py", line 445, in generate self.logger.debug(f'No new changes for {outfile}') File "/usr/local/lib/python3.9/logging/__init__.py", line 1434, in debug self._log(DEBUG, msg, args, **kwargs) File "/usr/local/lib/python3.9/logging/__init__.py", line 1589, in _log self.handle(record) File "/usr/local/lib/python3.9/logging/__init__.py", line 1599, in handle self.callHandlers(record) File "/usr/local/lib/python3.9/logging/__init__.py", line 1661, in callHandlers hdlr.handle(record) File "/usr/local/lib/python3.9/logging/__init__.py", line 952, in handle self.emit(record) File "/usr/local/lib/python3.9/logging/handlers.py", line 75, in emit logging.FileHandler.emit(self, record) File "/usr/local/lib/python3.9/logging/__init__.py", line 1187, in emit StreamHandler.emit(self, record) File "/usr/local/lib/python3.9/logging/__init__.py", line 1087, in emit self.flush() File "/usr/local/lib/python3.9/logging/__init__.py", line 1067, in flush self.stream.flush() [2022/10/06 01:15:07] (DEBUG) EtcService.generate():445 - No new changes for /etc/pam.d/sshd [2022/10/06 01:15:07] (DEBUG) EtcService.generate():429 - mako:local/users.oath file removed. [2022/10/06 01:15:07] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/avahi/avahi-daemon.conf [2022/10/06 01:20:57] (INFO) middlewared.terminate():1635 - Terminating [2022/10/06 01:20:57] (INFO) middlewared.terminate():1635 - Terminating [2022/10/06 01:20:57] (INFO) middlewared.terminate():1635 - Terminating [2022/10/06 14:40:03] (INFO) middlewared.__init__():799 - Starting TrueNAS-13.0-U2 middleware [2022/10/06 07:40:06] (DEBUG) middlewared.setup():1717 - Timezone set to America/Los_Angeles [2022/10/06 07:40:09] (DEBUG) middlewared.setup():2853 - Certificate setup for System complete [2022/10/06 07:40:09] (INFO) middlewared.devd_listen():58 - devd connection established [2022/10/06 07:40:09] (DEBUG) middlewared.__plugins_setup():915 - All plugins loaded [2022/10/06 07:40:09] (DEBUG) middlewared.__initialize():1629 - Accepting connections [2022/10/06 07:40:10] (INFO) DiskService.log_disk_info():123 - Found disks: {'da3': {'name': 'da3', 'ident': '2YJD81KD ', 'lunid': '5000cca273e1c1ae', 'serial': '2YJD81KD '}, 'da2': {'name': 'da2', 'ident': '2YJDWHLD ', 'lunid': '5000cca273e20ab4', 'serial': '2YJDWHLD '}, 'da1': {'name': 'da1', 'ident': '2YJ2XJ3D ', 'lunid': '5000cca273dd82cf', 'serial': '2YJ2XJ3D '}, 'da0': {'name': 'da0', 'ident': '2YJD2H6D ', 'lunid': '5000cca273e1accf', 'serial': '2YJD2H6D '}, 'ada0': {'name': 'ada0', 'ident': '', 'lunid': None, 'serial': ''}} [2022/10/06 07:40:10] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 07:40:10] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 07:40:11] (DEBUG) EtcService.generate():445 - No new changes for /etc/hosts [2022/10/06 07:40:11] (DEBUG) EtcService.generate():445 - No new changes for /etc/pam.d/sshd [2022/10/06 07:40:11] (DEBUG) EtcService.generate():429 - mako:local/users.oath file removed. [2022/10/06 07:40:11] (DEBUG) EtcService.generate():429 - mako:local/openvpn/server/openvpn_server.conf file removed. [2022/10/06 07:40:11] (DEBUG) EtcService.generate():429 - mako:local/openvpn/client/openvpn_client.conf file removed. [2022/10/06 07:40:11] (DEBUG) EtcService.generate():429 - mako:wireguard/wg0.conf file removed. [2022/10/06 07:40:15] (DEBUG) middlewared.dumpdev_configure():24 - Configured "/dev/da3p1" device for crash dumps. [2022/10/06 07:40:16] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/smb4.conf [2022/10/06 07:40:16] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/smbusername.map [2022/10/06 07:40:16] (DEBUG) EtcService.generate():445 - No new changes for /etc/group [2022/10/06 07:40:16] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/sudoers [2022/10/06 07:40:16] (DEBUG) EtcService.generate():445 - No new changes for /etc/mail/aliases [2022/10/06 07:40:16] (DEBUG) EtcService.generate():445 - No new changes for /etc/security/pam_winbind.conf [2022/10/06 07:40:17] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/apache24/httpd.conf [2022/10/06 07:40:17] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/apache24/Includes/webdav.conf [2022/10/06 07:40:17] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/proftpd.conf [2022/10/06 07:40:17] (INFO) ZFSSnapshot.do_create():1019 - Snapshot taken: Pool/.system/samba4@wbc-1665067217 [2022/10/06 07:40:18] (DEBUG) middlewared.interface_pre_sync_hook():1567 - No HA hardware detected, skipping interfaces setup. [2022/10/06 07:40:18] (DEBUG) InterfaceService.configure():154 - xn0: adding 192.168.1.10/255.255.255.0 [2022/10/06 07:40:18] (INFO) InterfaceService.sync():1955 - Interfaces in database: xn0 [2022/10/06 07:40:18] (DEBUG) EtcService.generate():445 - No new changes for /etc/dhclient.conf [2022/10/06 07:40:18] (INFO) RouteService.sync():2155 - Adding IPv4 default route to 192.168.1.1 [2022/10/06 07:40:18] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/smb4_share.conf [2022/10/06 07:40:18] (DEBUG) SharingSMBService.netconf():59 - netconf failure for command [['net', 'conf', 'addshare', 'Storage', '/mnt/Pool/General', 'writeable=y', 'guest_ok=N']] stdout: [2022/10/06 07:40:18] (WARNING) SharingSMBService.sync_registry():1304 - Failed to add SMB share [storage] while synchronizing registry config Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb.py", line 1302, in sync_registry await self.middleware.call('sharing.smb.reg_addshare', share_conf[0]) File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1278, in call return await self._call( File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1235, in _call return await methodobj(*prepared_call.args) File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb_/registry.py", line 76, in reg_addshare await self.netconf( File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb_/registry.py", line 61, in netconf raise CallError( middlewared.service_exception.CallError: [EFAULT] net conf addshare [Storage] failed with error: ERROR: share Storage already exists. [2022/10/06 07:40:18] (DEBUG) SharingSMBService.netconf():59 - netconf failure for command [['net', 'conf', 'addshare', 'General', '/mnt/Pool/General', 'writeable=y', 'guest_ok=N']] stdout: [2022/10/06 07:40:18] (WARNING) SharingSMBService.sync_registry():1304 - Failed to add SMB share [general] while synchronizing registry config Traceback (most recent call last): File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb.py", line 1302, in sync_registry await self.middleware.call('sharing.smb.reg_addshare', share_conf[0]) File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1278, in call return await self._call( File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1235, in _call return await methodobj(*prepared_call.args) File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb_/registry.py", line 76, in reg_addshare await self.netconf( File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/smb_/registry.py", line 61, in netconf raise CallError( middlewared.service_exception.CallError: [EFAULT] net conf addshare [General] failed with error: ERROR: share General already exists. [2022/10/06 07:40:18] (DEBUG) EtcService.generate():445 - No new changes for /etc/nsswitch.conf [2022/10/06 07:40:18] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/nslcd.conf [2022/10/06 07:40:49] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 07:40:49] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 07:40:49] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task alert.flush_alerts to run every 3600 seconds [2022/10/06 07:40:49] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task alert.process_alerts to run every 60 seconds [2022/10/06 07:40:49] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task certificate.renew_certs to run every 86400 seconds [2022/10/06 07:40:49] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task certificateauthority.crl_generation to run every 86400 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task hardware.events.retrieve_logs to run every 86400 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task kerberos.keytab.check_updated_keytab to run every 3600 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task kmip.sync_keys to run every 86400 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task mail.send_mail_queue to run every 600 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task plugin.periodic_plugin_update to run every 86400 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task plugin.retrieve_versions_for_repos to run every 86400 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task pool.dataset.sync_db_keys to run every 86400 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task service.check_deprecated_services to run every 3600 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task smb.sharesec.check_share_info_tdb to run every 3600 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task truecommand.health_check to run every 1800 seconds [2022/10/06 07:40:50] (DEBUG) middlewared._setup_periodic_tasks():928 - Setting up periodic task zettarepl.flush_state to run every 3600 seconds [2022/10/06 07:40:50] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/avahi/avahi-daemon.conf [2022/10/06 07:40:50] (DEBUG) JailService.start_on_boot():1656 - Starting jails on boot: PENDING [2022/10/06 07:40:50] (DEBUG) JailService.start_on_boot():1658 - Starting jails on boot: SUCCESS [2022/10/06 07:40:52] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/wsdd.conf [2022/10/06 07:41:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 07:41:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 07:41:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 07:41:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 07:41:01] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 07:41:11] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:12:amd64/quarterly/packagesite.txz HTTP/1.1" 200 6506544 [2022/10/06 07:41:12] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:12:amd64/latest/packagesite.txz HTTP/1.1" 200 6708016 [2022/10/06 07:41:12] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): download.truenas.com:80 [2022/10/06 07:41:12] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 07:41:12] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 07:41:12] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): download.truenas.com:80 [2022/10/06 07:41:12] (DEBUG) urllib3.connectionpool._new_conn():227 - Starting new HTTP connection (1): pkg.freebsd.org:80 [2022/10/06 07:41:12] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/quarterly/packagesite.txz HTTP/1.1" 200 6699712 [2022/10/06 07:41:12] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/latest/packagesite.txz HTTP/1.1" 200 6693688 [2022/10/06 07:41:12] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/latest/packagesite.txz HTTP/1.1" 200 6693688 [2022/10/06 07:41:22] (DEBUG) urllib3.connectionpool._make_request():452 - http://download.truenas.com:80 "GET /plugins/iconik/stable/12.3-RELEASE/packagesite.txz HTTP/1.1" 200 62924 [2022/10/06 07:41:22] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/quarterly/packagesite.txz HTTP/1.1" 200 6699712 [2022/10/06 07:41:22] (DEBUG) urllib3.connectionpool._make_request():452 - http://download.truenas.com:80 "GET /plugins/asigra/stable/12.3-RELEASE/packagesite.txz HTTP/1.1" 200 16964 [2022/10/06 07:41:22] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/latest/packagesite.txz HTTP/1.1" 200 6693688 [2022/10/06 07:41:22] (DEBUG) urllib3.connectionpool._make_request():452 - http://pkg.freebsd.org:80 "GET /FreeBSD:13:amd64/latest/packagesite.txz HTTP/1.1" 200 6693688 [2022/10/06 10:30:00] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 10:30:00] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 12:26:43] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:26:53] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 12:26:53] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 12:26:53] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:27:01] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:27:03] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 12:27:03] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:27:11] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 12:27:11] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 12:27:11] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:27:14] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 12:27:14] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 12:27:19] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 12:27:19] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:27:21] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 12:27:21] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:27:26] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:27:29] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 12:27:29] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 12:27:29] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 12:27:29] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:27:31] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 12:27:31] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 12:27:36] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 12:27:36] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 12:27:36] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:27:37] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:27:40] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 12:27:40] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpia5_c555.pem, pool=None, required=1028) [2022/10/06 12:27:40] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 12:27:40] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 12:27:40] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:27:47] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 12:27:47] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:27:47] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 12:27:47] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 12:27:47] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:27:50] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 12:27:50] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:27:57] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 12:27:57] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 12:27:57] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 12:27:57] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:28:00] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 12:28:00] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpx0zuxyng, pool=None, required=17) [2022/10/06 12:28:00] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 12:28:02] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 12:28:02] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:28:07] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 12:28:07] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 12:28:12] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 12:28:12] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:28:13] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 12:28:13] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 12:28:13] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 12:28:13] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:28:23] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 12:28:23] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 12:28:23] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 12:28:23] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:28:23] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 12:28:23] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpc8_yv2lh.pem, pool=None, required=1028) [2022/10/06 12:28:23] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 12:28:23] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 12:28:23] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:28:33] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 12:28:33] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp2298g5ln.pem, pool=None, required=1028) [2022/10/06 12:28:33] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 12:28:33] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 12:28:33] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:28:33] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 12:28:33] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:28:43] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 12:28:43] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpy4cf2svc, pool=None, required=17) [2022/10/06 12:28:43] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 12:28:44] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 12:28:44] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:28:54] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 12:28:54] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp95kbeoy7, pool=None, required=17) [2022/10/06 12:28:54] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 12:30:22] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:30:33] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 12:30:33] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 12:30:33] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:30:43] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/ssh/sshd_config [2022/10/06 12:30:43] (DEBUG) EtcService.generate():445 - No new changes for /etc/pam.d/sshd [2022/10/06 12:30:43] (DEBUG) EtcService.generate():429 - mako:local/users.oath file removed. [2022/10/06 12:30:43] (DEBUG) EtcService.generate():445 - No new changes for /etc/local/avahi/avahi-daemon.conf [2022/10/06 12:30:43] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 12:30:43] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:30:45] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 12:30:53] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 12:30:53] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 12:30:58] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 12:30:58] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:31:09] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 12:31:09] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 12:31:09] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 12:31:09] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:31:19] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 12:31:19] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp7gd2tk9b.pem, pool=None, required=1028) [2022/10/06 12:31:19] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 12:31:19] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 12:31:19] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:31:30] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 12:31:30] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:31:40] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 12:31:40] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp2xfosck7, pool=None, required=17) [2022/10/06 12:31:40] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 12:47:19] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:47:29] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 12:47:29] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 12:47:29] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:47:40] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 12:47:40] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:47:50] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 12:47:50] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 12:47:55] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 12:47:55] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:48:06] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 12:48:06] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 12:48:06] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 12:48:06] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 12:48:16] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 12:48:16] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmph96esfw0.pem, pool=None, required=1028) [2022/10/06 12:48:16] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 12:48:16] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 12:48:16] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 12:48:26] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 12:48:26] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 12:48:37] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 12:48:37] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmppkczyh8v, pool=None, required=17) [2022/10/06 12:48:37] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 13:35:25] (DEBUG) UsageService.start():56 - Scheduled next run in 36483 seconds [2022/10/06 14:52:42] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 14:52:53] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 14:52:53] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 14:52:53] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 14:53:03] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 14:53:03] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 14:53:13] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 14:53:13] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 14:53:18] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 14:53:18] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 14:53:29] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 14:53:29] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 14:53:29] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 14:53:29] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 14:53:39] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 14:53:39] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpmnu53u_n.pem, pool=None, required=1028) [2022/10/06 14:53:39] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 14:53:39] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 14:53:39] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 14:53:50] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 14:53:50] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 14:54:00] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 14:54:00] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpuz3otx1e, pool=None, required=17) [2022/10/06 14:54:00] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 15:18:00] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 15:18:06] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 15:18:11] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 15:18:11] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 15:18:11] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 15:18:16] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 15:18:16] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 15:18:16] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 15:18:21] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 15:18:21] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 15:18:27] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 15:18:27] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 15:18:31] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 15:18:31] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 15:18:36] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 15:18:36] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 15:18:37] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 15:18:37] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 15:18:42] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 15:18:42] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 15:18:47] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 15:18:47] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 15:18:47] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 15:18:47] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 15:18:52] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 15:18:52] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 15:18:52] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 15:18:52] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 15:18:57] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 15:18:57] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpwzc6zhou.pem, pool=None, required=1028) [2022/10/06 15:18:57] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 15:18:57] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 15:18:57] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 15:19:03] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 15:19:03] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp4jubuqdv.pem, pool=None, required=1028) [2022/10/06 15:19:03] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 15:19:03] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 15:19:03] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 15:19:07] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 15:19:07] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 15:19:13] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 15:19:13] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 15:19:17] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 15:19:17] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpn0s9kycz, pool=None, required=17) [2022/10/06 15:19:18] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 15:19:23] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 15:19:23] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpginiab75, pool=None, required=17) [2022/10/06 15:19:23] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 15:30:00] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 15:30:00] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 17:24:59] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 17:25:09] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 17:25:09] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 17:25:09] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 17:25:19] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 17:25:19] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 17:25:29] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 17:25:29] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 17:25:35] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 17:25:35] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 17:25:45] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 17:25:45] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 17:25:45] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 17:25:45] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 17:25:56] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 17:25:56] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpuq2kpbkg.pem, pool=None, required=1028) [2022/10/06 17:25:56] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 17:25:56] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 17:25:56] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 17:26:06] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 17:26:06] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 17:26:16] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 17:26:16] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpdgb0lh0z, pool=None, required=17) [2022/10/06 17:26:16] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 19:02:08] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 19:02:19] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 19:02:19] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 19:02:19] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 19:02:29] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 19:02:29] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 19:02:39] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 19:02:39] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 19:02:44] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 19:02:44] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 19:02:55] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 19:02:55] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 19:02:55] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 19:02:55] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 19:03:05] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 19:03:05] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpn9a9sva7.pem, pool=None, required=1028) [2022/10/06 19:03:05] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 19:03:05] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 19:03:05] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 19:03:16] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 19:03:16] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 19:03:26] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 19:03:26] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp6hoc5tus, pool=None, required=17) [2022/10/06 19:03:26] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 19:34:05] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 19:34:16] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 19:34:16] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 19:34:16] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 19:34:26] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 19:34:26] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 19:34:36] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 19:34:36] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 19:34:41] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 19:34:41] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 19:34:52] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 19:34:52] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 19:34:52] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 19:34:52] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 19:35:02] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 19:35:02] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpjgpnaajz.pem, pool=None, required=1028) [2022/10/06 19:35:02] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 19:35:02] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 19:35:02] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 19:35:13] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 19:35:13] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 19:35:23] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 19:35:23] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp64j9n7n3, pool=None, required=17) [2022/10/06 19:35:23] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 19:45:36] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 19:45:46] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 19:45:46] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 19:45:46] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 19:45:56] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 19:45:56] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 19:46:06] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 19:46:06] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 19:46:11] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 19:46:11] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 19:46:22] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 19:46:22] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 19:46:22] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 19:46:22] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 19:46:32] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 19:46:32] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpfclof516.pem, pool=None, required=1028) [2022/10/06 19:46:32] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 19:46:32] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 19:46:32] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 19:46:43] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 19:46:43] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 19:46:53] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 19:46:53] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpg1m30btf, pool=None, required=17) [2022/10/06 19:46:53] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 20:23:51] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 20:23:58] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 20:26:33] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 20:26:33] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 20:26:33] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 20:27:24] (WARNING) middlewared._loop_monitor_thread():1538 - Task seems blocked: File "/usr/local/lib/python3.9/site-packages/middlewared/schema.py", line 975, in nf return await f(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/datastore/read.py", line 186, in config return await self.query(name, [], options) File "/usr/local/lib/python3.9/site-packages/middlewared/schema.py", line 975, in nf return await f(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/datastore/read.py", line 164, in query result = await self._queryset_serialize( File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/datastore/read.py", line 214, in _queryset_serialize result.append(await self._serialize( File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/datastore/read.py", line 232, in _serialize data = await self.middleware.call(extend, data) File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1278, in call return await self._call( File "/usr/local/lib/python3.9/site-packages/middlewared/main.py", line 1235, in _call return await methodobj(*prepared_call.args) File "/usr/local/lib/python3.9/site-packages/middlewared/plugins/sysdataset.py", line 75, in config_extend if not os.path.exists(SYSDATASET_PATH) or not os.path.ismount(SYSDATASET_PATH): File "/usr/local/lib/python3.9/genericpath.py", line 19, in exists os.stat(path) [2022/10/06 20:29:23] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 20:29:23] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 20:29:23] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 20:29:33] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 20:29:33] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 20:29:33] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 20:29:33] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 20:29:43] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 20:29:43] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 20:29:43] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 20:29:43] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 20:29:48] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 20:29:48] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 20:29:48] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 20:29:48] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 20:29:58] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 20:29:58] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 20:29:58] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 20:29:58] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 20:29:58] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 20:29:58] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 20:29:58] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 20:29:58] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 20:30:00] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 20:30:00] (DEBUG) EtcService.generate():445 - No new changes for /etc/krb5.conf [2022/10/06 20:30:09] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 20:30:09] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpw6l2vnp2.pem, pool=None, required=1028) [2022/10/06 20:30:09] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 20:30:09] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 20:30:09] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 20:30:09] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 20:30:09] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpc3uvp3na.pem, pool=None, required=1028) [2022/10/06 20:30:09] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 20:30:09] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 20:30:09] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 20:30:19] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 20:30:19] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 20:30:19] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 20:30:19] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 20:30:29] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 20:30:29] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp9bdtob12, pool=None, required=17) [2022/10/06 20:30:29] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 20:30:29] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 20:30:29] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpwhp0nuz6, pool=None, required=17) [2022/10/06 20:30:29] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total [2022/10/06 21:54:30] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 21:54:41] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/trains_redir.json HTTP/1.1" 200 379 [2022/10/06 21:54:41] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']) [2022/10/06 21:54:41] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 21:54:51] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/trains.txt HTTP/1.1" 301 169 [2022/10/06 21:54:51] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 21:55:01] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/trains.txt HTTP/1.1" 200 531 [2022/10/06 21:55:01] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/trains.txt', 'https://update-master.ixsystems.com/TrueNAS/trains.txt']): Read 531 bytes total [2022/10/06 21:55:06] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']) [2022/10/06 21:55:06] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 21:55:17] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /TrueNAS/TrueNAS-13.0-STABLE/LATEST HTTP/1.1" 200 1602 [2022/10/06 21:55:17] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/TrueNAS/TrueNAS-13.0-STABLE/LATEST']): Read 1602 bytes total [2022/10/06 21:55:17] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']) [2022/10/06 21:55:17] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update-master.ixsystems.com:443 [2022/10/06 21:55:27] (DEBUG) urllib3.connectionpool._make_request():452 - https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028 [2022/10/06 21:55:27] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmppqrum08u.pem, pool=None, required=1028) [2022/10/06 21:55:27] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total [2022/10/06 21:55:27] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']) [2022/10/06 21:55:27] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.ixsystems.com:443 [2022/10/06 21:55:38] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.ixsystems.com:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 301 169 [2022/10/06 21:55:38] (DEBUG) urllib3.connectionpool._new_conn():971 - Starting new HTTPS connection (1): update.freenas.org:443 [2022/10/06 21:55:48] (DEBUG) urllib3.connectionpool._make_request():452 - https://update.freenas.org:443 "GET /TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt HTTP/1.1" 200 17 [2022/10/06 21:55:48] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp154ovxlw, pool=None, required=17) [2022/10/06 21:55:48] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['https://update.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt', 'https://update-master.ixsystems.com/TrueNAS/Validators/ValidateUpdate-hqiir5_f.txt']): Read 17 bytes total