Moin,
ich habe gerade das gleiche Problem. Frischer OPSI 4.2 unter Ubuntu 18.04.
Beim Einspielen der Basisprodukte mittels "opsi-package-updater -v install" hängt die Installation für 10 Minuten, danach kommt der gleiche Fehler wie oben. Das passiert bei jedem win-netboot Produkt, egal ob win10, win8 oder sonst was. Anbei exemplarisch:
Code: Alles auswählen
[5] [2021-08-31 11:31:45.508] [package_install] Installing package file '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi' on depot 'opsi.domain.local' (Depotserver.py:333)
[5] [2021-08-31 11:31:45.638] [package_install] Locking product 'win10-x64' on depot 'opsi.domain.local' (Depotserver.py:208)
[5] [2021-08-31 11:31:45.649] [package_install] Running package script 'preinst' (Product.py:446)
[5] [2021-08-31 11:31:46.423] [package_install] Running package script 'postinst' (Product.py:446)
[3] [2021-08-31 11:41:46.424] [package_install] Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds (Product.py:460)
Traceback (most recent call last):
File "OPSI/Util/Product.py", line 458, in _runPackageScript
File "OPSI/System/Posix.py", line 982, in execute
RuntimeError: Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds
[4] [2021-08-31 11:41:46.426] [package_install] Installation error. Not unlocking product 'win10-x64' on depot 'opsi.domain.local'. (Depotserver.py:223)
[3] [2021-08-31 11:41:46.427] [package_install] Failed to execute package script 'postinst' of package '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi': Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds (Depotserver.py:502)
Traceback (most recent call last):
File "OPSI/Util/Product.py", line 458, in _runPackageScript
File "OPSI/System/Posix.py", line 982, in execute
RuntimeError: Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
File "OPSI/Backend/Depotserver.py", line 500, in installPackage
File "OPSI/Backend/Depotserver.py", line 488, in installPackage
File "contextlib.py", line 119, in __exit__
File "OPSI/Backend/Depotserver.py", line 245, in runPackageScripts
File "OPSI/Util/Product.py", line 472, in runPostinst
File "OPSI/Util/Product.py", line 464, in _runPackageScript
RuntimeError: Failed to execute package script 'postinst' of package '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi': Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds
[3] [2021-08-31 11:41:46.428] [ ] Backend error: Failed to install package '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi' on depot 'opsi.domain.local': Failed to execute package script 'postinst' of package '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi': Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds (opsipackageupdater.py:312)
Traceback (most recent call last):
File "OPSI/Util/Product.py", line 458, in _runPackageScript
File "OPSI/System/Posix.py", line 982, in execute
RuntimeError: Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
File "OPSI/Backend/Depotserver.py", line 500, in installPackage
File "OPSI/Backend/Depotserver.py", line 488, in installPackage
File "contextlib.py", line 119, in __exit__
File "OPSI/Backend/Depotserver.py", line 245, in runPackageScripts
File "OPSI/Util/Product.py", line 472, in runPostinst
File "OPSI/Util/Product.py", line 464, in _runPackageScript
RuntimeError: Failed to execute package script 'postinst' of package '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi': Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
File "opsiutils/opsipackageupdater.py", line 308, in main
File "opsiutils/opsipackageupdater.py", line 280, in updater_main
File "OPSI/Util/Task/UpdatePackages/Updater.py", line 363, in processUpdates
File "<string>", line 1, in depot_installPackage
File "OPSI/Backend/Base/Extended.py", line 123, in _executeMethod
File "<string>", line 1, in depot_installPackage
File "OPSI/Backend/Base/Extended.py", line 123, in _executeMethod
File "<string>", line 1, in depot_installPackage
File "OPSI/Backend/Base/Extended.py", line 123, in _executeMethod
File "OPSI/Backend/Depotserver.py", line 122, in depot_installPackage
File "OPSI/Backend/Depotserver.py", line 505, in installPackage
OPSI.Exceptions.BackendError: Backend error: Failed to install package '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi' on depot 'opsi.domain.local': Failed to execute package script 'postinst' of package '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi': Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds
ERROR: Backend error: Failed to install package '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi' on depot 'opsi.domain.local': Failed to execute package script 'postinst' of package '/var/lib/opsi/repository/win10-x64_4.2.0.1-2.opsi': Command '/tmp/.opsi.unpack.mH7CL/OPSI/postinst' timed out atfer 600.0006549358368 seconds
Das wäre die Ausgabe der laufenden Prozesse:
Code: Alles auswählen
~$ ps -Af | grep "[o]psi"
avahi 761 1 0 11:13 ? 00:00:00 avahi-daemon: running [opsi-lp-depot.local]
whoopsie 996 1 0 11:13 ? 00:00:00 /usr/bin/whoopsie -f
opsiadm+ 1843 1 0 11:14 ? 00:00:00 /lib/systemd/systemd --user
opsiadm+ 1844 1843 0 11:14 ? 00:00:00 (sd-pam)
opsiadm+ 1857 1 0 11:14 ? 00:00:00 /usr/bin/gnome-keyring-daemon --daemonize --login
opsiadm+ 1861 1839 0 11:14 tty2 00:00:00 /usr/lib/gdm3/gdm-x-session --run-script env GNOME_SHELL_SESSION_MODE=ubuntu gnome-session --session=ubuntu
opsiadm+ 1863 1861 0 11:14 tty2 00:00:11 /usr/lib/xorg/Xorg vt2 -displayfd 3 -auth /run/user/1000/gdm/Xauthority -background none -noreset -keeptty -verbose 3
opsiadm+ 1873 1843 0 11:14 ? 00:00:00 /usr/bin/dbus-daemon --session --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only
opsiadm+ 1876 1861 0 11:14 tty2 00:00:00 /usr/lib/gnome-session/gnome-session-binary --session=ubuntu
opsiadm+ 1971 1876 0 11:14 ? 00:00:00 /usr/bin/ssh-agent /usr/bin/im-launch env GNOME_SHELL_SESSION_MODE=ubuntu gnome-session --session=ubuntu
opsiadm+ 1977 1843 0 11:14 ? 00:00:00 /usr/lib/at-spi2-core/at-spi-bus-launcher
opsiadm+ 1982 1977 0 11:14 ? 00:00:00 /usr/bin/dbus-daemon --config-file=/usr/share/defaults/at-spi2/accessibility.conf --nofork --print-address 3
opsiadm+ 1984 1843 0 11:14 ? 00:00:00 /usr/lib/at-spi2-core/at-spi2-registryd --use-gnome-session
opsiadm+ 2010 1876 1 11:14 tty2 00:00:25 /usr/bin/gnome-shell
opsiadm+ 2020 1843 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfsd
opsiadm+ 2025 1843 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfsd-fuse /run/user/1000/gvfs -f -o big_writes
opsiadm+ 2036 1 2 11:14 ? 00:00:41 /usr/bin/pulseaudio --start --log-target=syslog
opsiadm+ 2047 1843 1 11:14 ? 00:00:25 /opt/teamviewer/tv_bin/TeamViewer_Desktop
opsiadm+ 2057 1843 3 11:14 ? 00:00:51 /opt/teamviewer/tv_bin/TeamViewer
opsiadm+ 2059 2010 0 11:14 tty2 00:00:00 ibus-daemon --xim --panel disable
opsiadm+ 2062 1843 0 11:14 ? 00:00:00 /usr/libexec/xdg-permission-store
opsiadm+ 2067 2059 0 11:14 tty2 00:00:00 /usr/lib/ibus/ibus-dconf
opsiadm+ 2070 1 0 11:14 tty2 00:00:00 /usr/lib/ibus/ibus-x11 --kill-daemon
opsiadm+ 2073 1843 0 11:14 ? 00:00:00 /usr/lib/ibus/ibus-portal
opsiadm+ 2083 1843 0 11:14 ? 00:00:00 /usr/lib/gnome-shell/gnome-shell-calendar-server
opsiadm+ 2091 1843 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfs-udisks2-volume-monitor
opsiadm+ 2095 1843 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfs-gphoto2-volume-monitor
opsiadm+ 2099 1843 0 11:14 ? 00:00:00 /usr/lib/evolution/evolution-source-registry
opsiadm+ 2106 1843 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfs-mtp-volume-monitor
opsiadm+ 2110 1843 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfs-goa-volume-monitor
opsiadm+ 2114 1843 0 11:14 ? 00:00:00 /usr/lib/gnome-online-accounts/goa-daemon
opsiadm+ 2137 1843 0 11:14 ? 00:00:00 /usr/lib/gnome-online-accounts/goa-identity-service
opsiadm+ 2140 1843 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfs-afc-volume-monitor
opsiadm+ 2155 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-power
opsiadm+ 2158 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-print-notifications
opsiadm+ 2162 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-rfkill
opsiadm+ 2165 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-screensaver-proxy
opsiadm+ 2167 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-sharing
opsiadm+ 2175 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-smartcard
opsiadm+ 2180 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-xsettings
opsiadm+ 2182 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-wacom
opsiadm+ 2184 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-sound
opsiadm+ 2191 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-a11y-settings
opsiadm+ 2192 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-clipboard
opsiadm+ 2196 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-color
opsiadm+ 2198 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-datetime
opsiadm+ 2199 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-housekeeping
opsiadm+ 2202 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-keyboard
opsiadm+ 2204 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-media-keys
opsiadm+ 2210 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-mouse
opsiadm+ 2237 1 0 11:14 tty2 00:00:00 /usr/lib/gnome-settings-daemon/gsd-printer
opsiadm+ 2263 1876 0 11:14 tty2 00:00:00 /usr/lib/gnome-disk-utility/gsd-disk-utility-notify
opsiadm+ 2264 1 0 11:14 tty2 00:00:01 /usr/bin/vmtoolsd -n vmusr --blockFd 3
opsiadm+ 2278 1876 0 11:14 tty2 00:00:01 nautilus-desktop
opsiadm+ 2293 2020 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfsd-trash --spawner :1.22 /org/gtk/gvfs/exec_spaw/0
opsiadm+ 2299 1843 0 11:14 ? 00:00:00 /usr/lib/evolution/evolution-calendar-factory
opsiadm+ 2309 2059 0 11:14 tty2 00:00:00 /usr/lib/ibus/ibus-engine-simple
opsiadm+ 2321 1843 0 11:14 ? 00:00:00 /usr/lib/dconf/dconf-service
opsiadm+ 2328 2299 0 11:14 ? 00:00:00 /usr/lib/evolution/evolution-calendar-factory-subprocess --factory all --bus-name org.gnome.evolution.dataserver.Subprocess.Backend.Calendarx2299x2 --own-path /org/gnome/evolution/dataserver/Subprocess/Backend/Calendar/2299/2
opsiadm+ 2339 1843 0 11:14 ? 00:00:00 /usr/lib/evolution/evolution-addressbook-factory
opsiadm+ 2349 2339 0 11:14 ? 00:00:00 /usr/lib/evolution/evolution-addressbook-factory-subprocess --factory all --bus-name org.gnome.evolution.dataserver.Subprocess.Backend.AddressBookx2339x2 --own-path /org/gnome/evolution/dataserver/Subprocess/Backend/AddressBook/2339/2
opsiadm+ 2384 2020 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfsd-network --spawner :1.22 /org/gtk/gvfs/exec_spaw/1
opsiadm+ 2389 2020 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfsd-smb-browse --spawner :1.22 /org/gtk/gvfs/exec_spaw/2
opsiadm+ 2398 2020 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfsd-dnssd --spawner :1.22 /org/gtk/gvfs/exec_spaw/3
opsiadm+ 2404 1843 0 11:14 ? 00:00:00 /usr/lib/gvfs/gvfsd-metadata
opsiadm+ 2428 1876 0 11:15 tty2 00:00:00 update-notifier
opsiadm+ 2430 1876 0 11:15 tty2 00:00:02 /usr/bin/gnome-software --gapplication-service
opsiadm+ 2595 1843 0 11:21 ? 00:00:01 /usr/lib/gnome-terminal/gnome-terminal-server
opsiadm+ 2605 2595 0 11:21 pts/0 00:00:00 bash
opsiadm+ 3103 2595 0 11:25 pts/1 00:00:00 bash
root 3493 1 0 11:30 ? 00:00:00 ./opsiconfd start --log-level-stderr=0
opsicon+ 3494 3493 0 11:30 ? 00:00:02 ./opsiconfd start --log-level-stderr=0
root 3593 1 0 11:30 ? 00:00:00 ./opsipxeconfd start
opsicon+ 3602 3494 0 11:30 ? 00:00:00 /usr/lib/opsiconfd/opsiconfd -B -S -E -s -c from multiprocessing.semaphore_tracker import main;main(25)
opsicon+ 3603 3494 0 11:30 ? 00:00:02 /usr/lib/opsiconfd/opsiconfd --multiprocessing-fork tracker_fd=26 pipe_handle=27
root 3706 2605 0 11:31 pts/0 00:00:00 sudo opsi-package-updater -v install
root 3707 3706 0 11:31 pts/0 00:00:00 /usr/lib/opsi-utils/opsi-package-updater -v install
root 3708 3707 3 11:31 pts/0 00:00:11 /usr/lib/opsi-utils/opsi-package-updater -v install
root 4099 3708 0 11:31 pts/0 00:00:00 /bin/sh -c /tmp/.opsi.unpack.mH7CL/OPSI/postinst
root 4100 4099 0 11:31 pts/0 00:00:00 /bin/bash /tmp/.opsi.unpack.mH7CL/OPSI/postinst
root 4150 4100 0 11:31 pts/0 00:00:00 /usr/lib/opsi-utils/opsi-python
root 4151 4150 0 11:31 pts/0 00:00:00 /usr/lib/opsi-utils/opsi-python
opsiadm+ 4157 3103 0 11:37 pts/1 00:00:00 ps -Af
opsiadm+ 4158 3103 0 11:37 pts/1 00:00:00 grep --color=auto [o]psi
Ein Log mit der IP oder dem Namen des Servers gibt es unter "/var/log/opsi/opsiconfd/" nicht, lediglich ein Log Names "opsiconfd.log" mit folgendem Inhalt:
Code: Alles auswählen
[4] [2021-08-31 10:38:52.505] [ ] Failed to set RLIMIT_NOFILE: current limit exceeds maximum limit (setup.py:50)
[1] [2021-08-31 10:38:54.293] [ ] Switching to user opsiconfd (main.py:170)
[1] [2021-08-31 10:38:54.294] [ ] opsiconfd is starting (main.py:186)
[1] [2021-08-31 10:38:54.296] [ ] Register zeroconf service (zeroconf.py:45)
[4] [2021-08-31 10:56:19.773] [ ] Failed to set RLIMIT_NOFILE: current limit exceeds maximum limit (setup.py:50)
[1] [2021-08-31 10:56:21.543] [ ] Switching to user opsiconfd (main.py:170)
[1] [2021-08-31 10:56:21.544] [ ] opsiconfd is starting (main.py:186)
[1] [2021-08-31 10:56:21.546] [ ] Register zeroconf service (zeroconf.py:45)
[4] [2021-08-31 11:13:12.963] [ ] Failed to set RLIMIT_NOFILE: current limit exceeds maximum limit (setup.py:50)
[1] [2021-08-31 11:13:15.171] [ ] Switching to user opsiconfd (main.py:170)
[1] [2021-08-31 11:13:15.172] [ ] opsiconfd is starting (main.py:186)
[1] [2021-08-31 11:13:15.175] [ ] Register zeroconf service (zeroconf.py:45)
[4] [2021-08-31 11:30:06.657] [ ] Failed to set RLIMIT_NOFILE: current limit exceeds maximum limit (setup.py:50)
[1] [2021-08-31 11:30:08.656] [ ] Switching to user opsiconfd (main.py:170)
[1] [2021-08-31 11:30:08.656] [ ] opsiconfd is starting (main.py:186)
[1] [2021-08-31 11:30:08.659] [ ] Register zeroconf service (zeroconf.py:45)