[2022/03/23 13:58:59] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update-master.ixsystems.com:443
[2022/03/23 13:59:00] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update-master.ixsystems.com:443 "GET /FreeNAS/trains_redir.json HTTP/1.1" 200 465
[2022/03/23 13:59:00] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['
https://update.ixsystems.com/FreeNAS/trains.txt', '
https://update-master.ixsystems.com/FreeNAS/trains.txt'])
[2022/03/23 13:59:00] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update.ixsystems.com:443
[2022/03/23 13:59:00] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update.ixsystems.com:443 "GET /FreeNAS/trains.txt HTTP/1.1" 301 169
[2022/03/23 13:59:00] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update.freenas.org:443
[2022/03/23 13:59:00] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update.freenas.org:443 "GET /FreeNAS/trains.txt HTTP/1.1" 200 475
[2022/03/23 13:59:00] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['
https://update.ixsystems.com/FreeNAS/trains.txt', '
https://update-master.ixsystems.com/FreeNAS/trains.txt']): Read 475 bytes total
[2022/03/23 13:59:00] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['
https://update-master.ixsystems.com/FreeNAS/FreeNAS-11.3-STABLE/LATEST'])
[2022/03/23 13:59:00] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update-master.ixsystems.com:443
[2022/03/23 13:59:01] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update-master.ixsystems.com:443 "GET /FreeNAS/FreeNAS-11.3-STABLE/LATEST HTTP/1.1" 200 1957
[2022/03/23 13:59:01] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['
https://update-master.ixsystems.com/FreeNAS/FreeNAS-11.3-STABLE/LATEST']): Read 1957 bytes total
[2022/03/23 13:59:01] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem'])
[2022/03/23 13:59:01] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update-master.ixsystems.com:443
[2022/03/23 13:59:01] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028
[2022/03/23 13:59:01] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpwmyhkcke.pem, pool=None, required=1028)
[2022/03/23 13:59:01] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total
[2022/03/23 13:59:01] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem'])
[2022/03/23 13:59:01] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update-master.ixsystems.com:443
[2022/03/23 13:59:01] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028
[2022/03/23 13:59:01] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmplrrla2gx.pem, pool=None, required=1028)
[2022/03/23 13:59:01] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total
[2022/03/23 13:59:03] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem'])
[2022/03/23 13:59:03] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update-master.ixsystems.com:443
[2022/03/23 13:59:04] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028
[2022/03/23 13:59:04] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpqypwkn1j.pem, pool=None, required=1028)
[2022/03/23 13:59:04] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total
[2022/03/23 13:59:04] (DEBUG) freenasOS.Update.DownloadUpdate():991 - DownloadUpdate: Cache directory has latest manifest
[2022/03/23 13:59:04] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem'])
[2022/03/23 13:59:04] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update-master.ixsystems.com:443
[2022/03/23 13:59:04] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028
[2022/03/23 13:59:04] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp4vq8zk0w.pem, pool=None, required=1028)
[2022/03/23 13:59:04] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total
[2022/03/23 13:59:04] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem'])
[2022/03/23 13:59:04] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update-master.ixsystems.com:443
[2022/03/23 13:59:04] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028
[2022/03/23 13:59:04] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmp411c_lku.pem, pool=None, required=1028)
[2022/03/23 13:59:04] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total
[2022/03/23 13:59:06] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem'])
[2022/03/23 13:59:06] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update-master.ixsystems.com:443
[2022/03/23 13:59:06] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028
[2022/03/23 13:59:06] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpl5w0eu9e.pem, pool=None, required=1028)
[2022/03/23 13:59:06] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total
[2022/03/23 13:59:06] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():606 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem'])
[2022/03/23 13:59:06] (DEBUG) urllib3.connectionpool._new_conn():959 - Starting new HTTPS connection (1): update-master.ixsystems.com:443
[2022/03/23 13:59:07] (DEBUG) urllib3.connectionpool._make_request():437 -
https://update-master.ixsystems.com:443 "GET /updates/ix_crl.pem HTTP/1.1" 200 1028
[2022/03/23 13:59:07] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=/tmp/tmpnw38qk1g.pem, pool=None, required=1028)
[2022/03/23 13:59:07] (DEBUG) freenasOS.Configuration.TryGetNetworkFile():745 - TryGetNetworkFile(['
https://update-master.ixsystems.com/updates/ix_crl.pem']): Read 1028 bytes total
[2022/03/23 13:59:07] (DEBUG) freenasOS.Update.ApplyUpdate():1342 - Upgrade package base-os-11.3-U3.2-34e407923c7ab791e652f69528c42184 to base-os-11.3-U5-e1b01c8c85d9ad63759e484f30efb325
[2022/03/23 13:59:07] (DEBUG) freenasOS.Update.ApplyUpdate():1342 - Upgrade package freebsd-pkgdb-11.3-U3.2-34e407923c7ab791e652f69528c42184 to freebsd-pkgdb-11.3-U5-e1b01c8c85d9ad63759e484f30efb325
[2022/03/23 13:59:07] (DEBUG) freenasOS.Update.ApplyUpdate():1342 - Upgrade package freenas-pkg-tools-11.3-U3.2-34e407923c7ab791e652f69528c42184 to freenas-pkg-tools-11.3-U5-e1b01c8c85d9ad63759e484f30efb325
[2022/03/23 13:59:07] (DEBUG) freenasOS.Update.ApplyUpdate():1342 - Upgrade package FreeNASUI-11.3-U3.2-34e407923c7ab791e652f69528c42184 to FreeNASUI-11.3-U5-e1b01c8c85d9ad63759e484f30efb325
[2022/03/23 13:59:07] (DEBUG) freenasOS.Update.ApplyUpdate():1352 - new_boot_name = 11.3-U5, reboot = True
[2022/03/23 13:59:07] (DEBUG) freenasOS.Configuration.FindPackageFile():1214 - Searching for base-os-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz
[2022/03/23 13:59:07] (DEBUG) freenasOS.Configuration.FindPackageFile():1220 - Found package file /var/db/system/update/base-os-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz
[2022/03/23 13:59:09] (DEBUG) freenasOS.Configuration.FindPackageFile():1214 - Searching for freebsd-pkgdb-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz
[2022/03/23 13:59:09] (DEBUG) freenasOS.Configuration.FindPackageFile():1220 - Found package file /var/db/system/update/freebsd-pkgdb-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz
[2022/03/23 13:59:09] (DEBUG) freenasOS.Configuration.FindPackageFile():1214 - Searching for freenas-pkg-tools-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz
[2022/03/23 13:59:09] (DEBUG) freenasOS.Configuration.FindPackageFile():1220 - Found package file /var/db/system/update/freenas-pkg-tools-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz
[2022/03/23 13:59:09] (DEBUG) freenasOS.Configuration.FindPackageFile():1214 - Searching for FreeNASUI-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz
[2022/03/23 13:59:09] (DEBUG) freenasOS.Configuration.FindPackageFile():1220 - Found package file /var/db/system/update/FreeNASUI-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz
[2022/03/23 13:59:09] (DEBUG) freenasOS.Update.ApplyUpdate():1363 - Installer got packages [{'base-os': <_io.BufferedReader name='/var/db/system/update/base-os-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz'>}, {'freebsd-pkgdb': <_io.BufferedReader name='/var/db/system/update/freebsd-pkgdb-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz'>}, {'freenas-pkg-tools': <_io.BufferedReader name='/var/db/system/update/freenas-pkg-tools-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz'>}, {'FreeNASUI': <_io.BufferedReader name='/var/db/system/update/FreeNASUI-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz'>}]
[2022/03/23 13:59:09] (DEBUG) freenasOS.Configuration.CheckFreeSpace():77 - CheckFreeSpace(path=None, pool=freenas-boot, required=2142564352)
[2022/03/23 13:59:09] (DEBUG) freenasOS.Update.PruneClones():402 - No pruning necessary
[2022/03/23 13:59:09] (DEBUG) freenasOS.Update.RunCommand():254 - RunCommand(/usr/local/sbin/beadm, ['create', '11.3-U5'])
[2022/03/23 13:59:10] (DEBUG) freenasOS.Update.modified_call():105 - Created successfully
[2022/03/23 13:59:10] (DEBUG) freenasOS.Update.RunCommand():254 - RunCommand(/usr/local/sbin/beadm, ['mount', '11.3-U5', '/tmp/tmp2167byli'])
[2022/03/23 13:59:10] (DEBUG) freenasOS.Update.modified_call():105 - Mounted successfully on '/tmp/tmp2167byli'
[2022/03/23 13:59:10] (DEBUG) freenasOS.Update.RunCommand():254 - RunCommand(/sbin/mount, ['-t', 'devfs', 'devfs', '/tmp/tmp2167byli/dev'])
[2022/03/23 13:59:10] (DEBUG) freenasOS.Update.RunCommand():254 - RunCommand(/sbin/mount, ['-t', 'tmpfs', 'tmpfs', '/tmp/tmp2167byli/var/tmp'])
[2022/03/23 13:59:10] (DEBUG) freenasOS.Installer.InstallPackages():1083 - Installing package {'base-os': <_io.BufferedReader name='/var/db/system/update/base-os-11.3-U5-e1b01c8c85d9ad63759e484f30efb325.tgz'>}
[2022/03/23 13:59:11] (DEBUG) freenasOS.Installer.install_file():711 - base-os-11.3-U5-e1b01c8c85d9ad63759e484f30efb325
[2022/03/23 13:59:11] (DEBUG) freenasOS.Installer.install_file():745 - upgrade_aware = True
[2022/03/23 13:59:11] (ERROR) freenasOS.Update.ApplyUpdate():1594 - Update got exception during update: Cannot remove file /tmp
Traceback (most recent call last):
File "/usr/local/lib/freenasOS/Update.py", line 1559, in ApplyUpdate
if installer.InstallPackages(progressFunc=progressFunc, handler=install_handler) is False:
File "/usr/local/lib/freenasOS/Installer.py", line 1088, in InstallPackages
trampoline=self.trampoline) is False:
File "/usr/local/lib/freenasOS/Installer.py", line 823, in install_file
if pkgdb.RemovePackageFiles(pkgName) == False:
File "/usr/local/lib/freenasOS/Configuration.py", line 347, in RemovePackageFiles
raise Exception("Cannot remove file %s" % path)
Exception: Cannot remove file /tmp
[2022/03/23 13:59:11] (DEBUG) freenasOS.Update.RunCommand():254 - RunCommand(/sbin/umount, ['-f', '/tmp/tmp2167byli/dev'])
[2022/03/23 13:59:11] (DEBUG) freenasOS.Update.RunCommand():254 - RunCommand(/sbin/umount, ['-f', '/tmp/tmp2167byli/var/tmp'])
[2022/03/23 13:59:11] (DEBUG) freenasOS.Update.RunCommand():254 - RunCommand(/usr/local/sbin/beadm, ['unmount', '-f', '11.3-U5'])
[2022/03/23 13:59:11] (DEBUG) freenasOS.Update.modified_call():105 - Unmounted successfully
[2022/03/23 13:59:12] (DEBUG) freenasOS.Update.RunCommand():254 - RunCommand(/usr/local/sbin/beadm, ['destroy', '-F', '11.3-U5'])
[2022/03/23 13:59:12] (DEBUG) freenasOS.Update.modified_call():105 - Destroyed successfully