Bug 42333 - System setup ignores fatal errors
System setup ignores fatal errors
Status: NEW
Product: UCS
Classification: Unclassified
Component: System setup
UCS 5.0
Other Linux
: P5 normal (vote)
: ---
Assigned To: UCS maintainers
:
: 33418 (view as bug list)
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2016-09-09 10:40 CEST by Philipp Hahn
Modified: 2020-07-02 09:58 CEST (History)
3 users (show)

See Also:
What kind of report is it?: Bug Report
What type of bug is this?: 6: Setup Problem: Issue for the setup process
Who will be affected by this bug?: 1: Will affect a very few installed domains
How will those affected feel about the bug?: 5: Blocking further progress on the daily work
User Pain: 0.171
Enterprise Customer affected?:
School Customer affected?:
ISV affected?:
Waiting Support:
Flags outvoted (downgraded) after PO Review:
Ticket number:
Bug group (optional):
Max CVSS v3 score:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Philipp Hahn univentionstaff 2016-09-09 10:40:42 CEST
./univention/setup.log:__MSG__:univention-server-master (amd64) wird installiert.
./univention/setup.log:__MSG__:univention-server-master (amd64) wird vorbereitet.
./univention/setup.log:__MSG__:univention-server-master (amd64) wird entpackt.
./univention/setup.log:__MSG__:Konfiguration von univention-server-master (amd64) wird vorbereitet.
./univention/setup.log:__ERR__:univention-server-master: Failed to install

Very little works afterwards
Comment 1 Philipp Hahn univentionstaff 2016-09-15 13:27:54 CEST
Package "univention-server-master" is not configured
→ UCRV "ldap/server/type" is not set
  → "management/univention-ldap/01univention-ldap-server-init.inst" can setup LDAP
→ univention-role-server-common is not configured
  → univention-home-mounter is not configured
    → nfs-common is not configured
      → "update-rc.d nfs-common defaults": insserv rejected the script header
        → Service rpcbind has to be enabled
          → Running in chroot, ignoring request.
  → univention-nfs-server is not configured

has been fixed, but the issue remains.
Comment 2 Florian Best univentionstaff 2016-10-10 14:49:16 CEST
For the bugzilla search:

univention-server-master: Failed to uninstall
univention-server-backup: Failed to install
Comment 3 Florian Best univentionstaff 2016-10-10 14:49:27 CEST
*** Bug 33418 has been marked as a duplicate of this bug. ***
Comment 4 Florian Best univentionstaff 2016-10-10 14:49:47 CEST
(In reply to Florian Best from comment #3)
> *** Bug 33418 has been marked as a duplicate of this bug. ***
This is one example of this bug.
Comment 5 Philipp Hahn univentionstaff 2016-11-26 16:51:09 CET
Task #4777: 2016-11-24/25 - UCS-Technikschulung - Bremen

/usr/lib/univention-system-setup/scripts/setup-join.sh:
>219 run-parts -v /usr/lib/univention-system-setup/scripts/50_software

/var/log/installer/syslog:
>=== 50_software/10software (2016-11-25 18:26:19) ===
>__NAME__:50_software/10software Einrichten der Software-Komponenten
>__STEPS__:900
>File: /etc/apt/sources.list.d/15_ucs-online-version.list
># An error occurred during the repository check. The error message:
>__MSG__:Es wurden 0 B in 0 s geholt (0 B/s)
>__ERR__:Fetching failed
>__ERR__:
>Further information regarding this error:
>Error: Problem executing scripts APT::Update::Pre-Invoke 'grep -Fqs '# An error occurred during the repository check.' /etc/apt/sources.list.d/15_ucs-online-version.list && ucr commit /etc/apt/sources.list.d/15_ucs-online-version.list ; ! grep -F '# An error occurred during the repository check.' /etc/apt/sources.list.d/15_ucs-online-version.list'.
>__ERR__:Error: Sub-process returned an error code.
>run-parts: /usr/lib/univention-system-setup/scripts/50_software/10software exited with return code 1


As the script does not "set -e", it ignores all errors.
After the installation /etc/apt/sources.list.d/15_ucs-online-version.list looks sane, but all component packages were NOT installed.

I was able to reproduce this behavior after the training: 3/6 failed, after that 2/3.

Around 18:26:19 several daemons were restarted:

>Nov 25 18:26:10 logger: /etc/init.d/slapd restart (pid: 13068, ppid:13051 invoke-rc.d)
>Nov 25 18:26:10 logger: /etc/init.d/slapd stop (pid: 13076, ppid:13068 slapd)
>Nov 25 18:26:10 slapd[8458]: daemon: shutdown requested and initiated.
>Nov 25 18:26:10 slapd[8458]: slapd shutdown: waiting for 0 operations/tasks to finish
>Nov 25 18:26:10 slapd[8458]: DIGEST-MD5 common mech free
>Nov 25 18:26:10 slapd[8458]: slapd stopped.
>Nov 25 18:26:10 logger: /etc/init.d/slapd start (pid: 13089, ppid:13068 slapd)
...
>Nov 25 18:26:18 postfix/master[13319]: daemon started -- version 2.9.6, configuration /etc/postfix
...
>Nov 25 18:26:20 slapd[13101]: daemon: shutdown requested and initiated.
>Nov 25 18:26:20 slapd[13101]: slapd shutdown: waiting for 0 operations/tasks to finish
>Nov 25 18:26:20 slapd[13101]: DIGEST-MD5 common mech free
>Nov 25 18:26:20 slapd[13101]: slapd stopped.
...

Th first restart was triggered by /usr/lib/univention-system-setup/scripts/40_ssl/10ssl, which alt least restarts "slapd apache2 cyrus postfix".
I also see DNS requests for:
> Nov 25 18:26:18 named[31433]: error (network unreachable) resolving 'univention-repository.knut.univention.de.schulung7.ucs/A/IN': 2001:500:2d::d#53
(Yes I used out internal repository for installation while the PCs were still connected to KNUT)
Comment 6 Philipp Hahn univentionstaff 2016-11-30 08:20:59 CET
# An error occurred during the repository check. The error message:
#   Traceback (most recent call last):
#     File "<stdin>", line 13, in <module>
#     File "/usr/lib/pymodules/python2.7/univention/updater/tools.py", line 579, in __init__
#       self.ucr_reinit()
#     File "/usr/lib/pymodules/python2.7/univention/updater/tools.py", line 651, in ucr_reinit
#       assert self.server.access(None, '/univention-repository/')
#     File "/usr/lib/pymodules/python2.7/univention/updater/tools.py", line 488, in access
#       raise ConfigurationError(uri, reason)
#   ConfigurationError: Configuration error: host is unresolvable
#

This was caused by one "named" running from Debians /etc/init.d/bind9, which does not get terminated when the UCS ones try to start; that one has no forwarders set and thus resolves "univention-repository.knut.univention.de" by appending its DNS domain name, which fails.
Maybe that (BIND started by Debian's init.d before the switch-over to UCS-BIND-{ldap,proxy,samba}) should be checked too.
Comment 7 Florian Best univentionstaff 2020-07-02 09:58:03 CEST
Still with UCS 5.0:

[master090] 2020-07-01T23:40:31.907963	__ERR__:Traceback (most recent call last):
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/univention/management/console/modules/setup/setup_script.py", line 313, in run
[master090] 2020-07-01T23:40:31.907963	__ERR__:    success = self.inner_run()
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/univention-system-setup/scripts/50_software/10software", line 76, in inner_run
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.autoremove()
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/univention/management/console/modules/setup/setup_script.py", line 443, in autoremove
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.package_manager.autoremove()
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 1111, in autoremove
[master090] 2020-07-01T23:40:31.907963	__ERR__:    return self.commit(msg_if_failed=failed_msg)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 945, in commit
[master090] 2020-07-01T23:40:31.907963	__ERR__:    result = self.cache.commit(**kwargs)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/apt/cache.py", line 549, in commit
[master090] 2020-07-01T23:40:31.907963	__ERR__:    res = self.install_archives(pm, install_progress)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/apt/cache.py", line 508, in install_archives
[master090] 2020-07-01T23:40:31.907963	__ERR__:    res = install_progress.run(pm)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/apt/progress/base.py", line 208, in run
[master090] 2020-07-01T23:40:31.907963	__ERR__:    res = self.wait_child()
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/apt/progress/base.py", line 279, in wait_child
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.update_interface()
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/apt/progress/base.py", line 256, in update_interface
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.status_change(pkgname, float(percent), status_str.strip())
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 349, in status_change
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.progress_state.percentage(str(percent))
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 143, in percentage
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.get_logger(logger_name).info(percentage)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/logging/__init__.py", line 1167, in info
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self._log(INFO, msg, args, **kwargs)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/logging/__init__.py", line 1286, in _log
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.handle(record)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/logging/__init__.py", line 1296, in handle
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.callHandlers(record)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/logging/__init__.py", line 1336, in callHandlers
[master090] 2020-07-01T23:40:31.907963	__ERR__:    hdlr.handle(record)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/logging/__init__.py", line 759, in handle
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.emit(record)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/univention/management/console/modules/setup/setup_script.py", line 357, in emit
[master090] 2020-07-01T23:40:31.907963	__ERR__:    super(_PackageManagerLoggerHandlerWithoutProcess, self).emit(record)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 403, in emit
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self.step_handler(record.msg)
[master090] 2020-07-01T23:40:31.907963	__ERR__:  File "/usr/lib/python2.7/dist-packages/univention/management/console/modules/setup/setup_script.py", line 285, in step
[master090] 2020-07-01T23:40:31.907963	__ERR__:    self._step += 1
[master090] 2020-07-01T23:40:31.907963	__ERR__:TypeError: cannot concatenate 'str' and 'int' objects
[master090] 2020-07-01T23:40:31.908478	Traceback (most recent call last):
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/univention/management/console/modules/setup/setup_script.py", line 313, in run
[master090] 2020-07-01T23:40:31.908478	    success = self.inner_run()
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/univention-system-setup/scripts/50_software/10software", line 76, in inner_run
[master090] 2020-07-01T23:40:31.908478	    self.autoremove()
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/univention/management/console/modules/setup/setup_script.py", line 443, in autoremove
[master090] 2020-07-01T23:40:31.908478	    self.package_manager.autoremove()
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 1111, in autoremove
[master090] 2020-07-01T23:40:31.908478	    return self.commit(msg_if_failed=failed_msg)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 945, in commit
[master090] 2020-07-01T23:40:31.908478	    result = self.cache.commit(**kwargs)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/apt/cache.py", line 549, in commit
[master090] 2020-07-01T23:40:31.908478	    res = self.install_archives(pm, install_progress)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/apt/cache.py", line 508, in install_archives
[master090] 2020-07-01T23:40:31.908478	    res = install_progress.run(pm)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/apt/progress/base.py", line 208, in run
[master090] 2020-07-01T23:40:31.908478	    res = self.wait_child()
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/apt/progress/base.py", line 279, in wait_child
[master090] 2020-07-01T23:40:31.908478	    self.update_interface()
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/apt/progress/base.py", line 256, in update_interface
[master090] 2020-07-01T23:40:31.908478	    self.status_change(pkgname, float(percent), status_str.strip())
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 349, in status_change
[master090] 2020-07-01T23:40:31.908478	    self.progress_state.percentage(str(percent))
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 143, in percentage
[master090] 2020-07-01T23:40:31.908478	    self.get_logger(logger_name).info(percentage)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/logging/__init__.py", line 1167, in info
[master090] 2020-07-01T23:40:31.908478	    self._log(INFO, msg, args, **kwargs)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/logging/__init__.py", line 1286, in _log
[master090] 2020-07-01T23:40:31.908478	    self.handle(record)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/logging/__init__.py", line 1296, in handle
[master090] 2020-07-01T23:40:31.908478	    self.callHandlers(record)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/logging/__init__.py", line 1336, in callHandlers
[master090] 2020-07-01T23:40:31.908478	    hdlr.handle(record)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/logging/__init__.py", line 759, in handle
[master090] 2020-07-01T23:40:31.908478	    self.emit(record)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/univention/management/console/modules/setup/setup_script.py", line 357, in emit
[master090] 2020-07-01T23:40:31.908478	    super(_PackageManagerLoggerHandlerWithoutProcess, self).emit(record)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 403, in emit
[master090] 2020-07-01T23:40:31.908478	    self.step_handler(record.msg)
[master090] 2020-07-01T23:40:31.908478	  File "/usr/lib/python2.7/dist-packages/univention/management/console/modules/setup/setup_script.py", line 285, in step
[master090] 2020-07-01T23:40:31.908478	    self._step += 1
[master090] 2020-07-01T23:40:31.908478	TypeError: cannot concatenate 'str' and 'int' objects
[master090] 2020-07-01T23:40:31.908478	 
[master090] 2020-07-01T23:40:32.043522	Exception in thread Thread-1 (most likely raised during interpreter shutdown):
[master090] 2020-07-01T23:40:32.043522	Traceback (most recent call last):
[master090] 2020-07-01T23:40:32.043522	  File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
[master090] 2020-07-01T23:40:32.043522	  File "/usr/lib/python2.7/threading.py", line 754, in run
[master090] 2020-07-01T23:40:32.043522	  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 331, in check_pipe
[master090] 2020-07-01T23:40:32.043522	  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 131, in info
[master090] 2020-07-01T23:40:32.043522	  File "/usr/lib/python2.7/dist-packages/univention/lib/package_manager.py", line 120, in get_logger
[master090] 2020-07-01T23:40:32.043522	  File "/usr/lib/python2.7/logging/__init__.py", line 1385, in getChild
[master090] 2020-07-01T23:40:32.043522	  File "/usr/lib/python2.7/logging/__init__.py", line 1037, in getLogger
[master090] 2020-07-01T23:40:32.043522	<type 'exceptions.TypeError'>: 'NoneType' object is not callable
[master090] 2020-07-01T23:40:32.061880	run-parts: /usr/lib/univention-system-setup/scripts/50_software/10software exited with return code 1


Installation of univention-server-* did not succeed and the Jenkins tests still continue so that the test takes ages and every test fails with some traceback about invalid credentials.