[Freeipa-users] sssd.service start operation timed out

Wed Mar 16 13:30:40 UTC 2016

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA256

Hi Jakub,

On 03/16/16 09:30, Jakub Hrozek wrote:
> 
> If you can reproduce the issue, it would be nice to increase the debug_level a bit so that the debug logs are more verbose..
> 

Using debug level 9 I got

(Wed Mar 16 13:24:57 2016) [sssd] [server_setup] (0x0400): CONFDB: /var/lib/sss/db/config.ldb
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_callback": 0xb3c2a0
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xb3c360
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Running timer event 0xb3c2a0 "ltdb_callback"
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Destroying timer event 0xb3c360 "ltdb_timeout"
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Ending timer event 0xb3c2a0 "ltdb_callback"
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): no modules required by the db
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): No modules specified for this database
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_callback": 0xb3c2d0
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xb3c390
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Running timer event 0xb3c2d0 "ltdb_callback"
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Destroying timer event 0xb3c390 "ltdb_timeout"
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Ending timer event 0xb3c2d0 "ltdb_callback"
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_callback": 0xb3c4e0
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xb3c5a0
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Running timer event 0xb3c4e0 "ltdb_callback"
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Destroying timer event 0xb3c5a0 "ltdb_timeout"
(Wed Mar 16 13:25:00 2016) [sssd] [ldb] (0x4000): Ending timer event 0xb3c4e0 "ltdb_callback"
(Wed Mar 16 13:25:00 2016) [sssd] [sysdb_domain_init_internal] (0x0200): DB File for example.com: /var/lib/sss/db/cache_example.com.ldb
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_callback": 0xb3dbe0
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xb3dca0
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Running timer event 0xb3dbe0 "ltdb_callback"
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Destroying timer event 0xb3dca0 "ltdb_timeout"
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Ending timer event 0xb3dbe0 "ltdb_callback"
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x0400): asq: Unable to register control with rootdse!
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_callback": 0xb3dd80
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xb3de40
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Running timer event 0xb3dd80 "ltdb_callback"
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Destroying timer event 0xb3de40 "ltdb_timeout"
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Ending timer event 0xb3dd80 "ltdb_callback"
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_callback": 0xb3dfd0
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xb3e090
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Running timer event 0xb3dfd0 "ltdb_callback"
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Destroying timer event 0xb3e090 "ltdb_timeout"
(Wed Mar 16 13:25:01 2016) [sssd] [ldb] (0x4000): Ending timer event 0xb3dfd0 "ltdb_callback"
(Wed Mar 16 13:25:04 2016) [sssd] [sbus_new_server] (0x0400): D-BUS Server listening on unix:path=/var/lib/sss/pipes/private/sbus-monitor,guid=d5f35f30568405c90a0fc9e756e950a0
(Wed Mar 16 13:25:05 2016) [sssd] [sbus_add_watch] (0x2000): 0xb3e070/0xb3dda0 (14), R/- (enabled)
(Wed Mar 16 13:25:05 2016) [sssd] [get_ping_config] (0x0100): Time between service pings for [example.com]: [10]
(Wed Mar 16 13:25:05 2016) [sssd] [get_ping_config] (0x0100): Time between SIGTERM and SIGKILL for [example.com]: [60]
(Wed Mar 16 13:25:05 2016) [sssd] [start_service] (0x0100): Queueing service example.com for startup
(Wed Mar 16 13:25:06 2016) [sssd] [monitor_quit_signal] (0x2000): Received shutdown command
(Wed Mar 16 13:25:08 2016) [sssd] [monitor_quit_signal] (0x0040): Monitor received Terminated: terminating children
(Wed Mar 16 13:25:08 2016) [sssd] [monitor_quit] (0x0040): Returned with: 0
(Wed Mar 16 13:25:08 2016) [sssd] [monitor_quit] (0x0020): Terminating [example.com][474]
(Wed Mar 16 13:25:08 2016) [sssd] [monitor_quit] (0x0020): Child [example.com] terminated with a signal
(Wed Mar 16 13:25:08 2016) [sssd] [monitor_cleanup] (0x0010): Error removing pidfile! (2 [No such file or directory])
(Wed Mar 16 13:25:08 2016) [sssd] [sbus_remove_watch] (0x2000): 0xb3e070/0xb3dda0

daemon.log shows
:
Mar 16 13:16:57 lxc10 systemd[1]: Stopping Getty on tty1...
Mar 16 13:16:57 lxc10 systemd[1]: Stopping Getty on tty4...
Mar 16 13:16:57 lxc10 systemd[1]: Stopping Container Getty on /dev/pts/3...
Mar 16 13:16:57 lxc10 systemd[1]: Stopping Container Getty on /dev/pts/2...
Mar 16 13:24:28 lxc10 systemd[1]: Started Remount Root and Kernel File Systems.
Mar 16 13:24:28 lxc10 systemd[1]: Started Various fixups to make systemd work better on Debian.
Mar 16 13:24:28 lxc10 systemd[1]: Starting Load/Save Random Seed...
Mar 16 13:24:28 lxc10 systemd[1]: Starting Local File Systems (Pre).
:
Mar 16 13:24:28 lxc10 systemd[1]: Started System Logging Service.
Mar 16 13:24:41 lxc10 exim4[189]: Starting MTA: exim4.
Mar 16 13:24:41 lxc10 systemd[1]: Started LSB: exim Mail Transport Agent.
Mar 16 13:24:43 lxc10 dbus[191]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30000ms)
Mar 16 13:24:57 lxc10 sssd: Starting up
Mar 16 13:25:06 lxc10 systemd[1]: sssd.service start operation timed out. Terminating.
Mar 16 13:25:06 lxc10 systemd[1]: Failed to start System Security Services Daemon.
Mar 16 13:25:06 lxc10 systemd[1]: Unit sssd.service entered failed state.
Mar 16 13:25:06 lxc10 systemd[1]: Starting User and Group Name Lookups.
Mar 16 13:25:06 lxc10 systemd[1]: Reached target User and Group Name Lookups.
Mar 16 13:25:06 lxc10 systemd[1]: Starting Login Service...
Mar 16 13:25:06 lxc10 systemd[1]: Starting Permit User Sessions...
:

Does this help?

Regards
Harri

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v2

iQEcBAEBCAAGBQJW6WAAAAoJEAqeKp5m04HLcLwIAIU6Mjg3aTe6CHDdOUi6Nvt7
EBMDrmAS5nXV5CM/CiQc7JitUQPTmwhjTTOERUXu2o3f1L9LLwC2zTLbB0oLnqBX
ZNCZ/tjrMZBSSO6jAxmfcRlBOemsVinNFBavtpxQSvwoZ9wokUe1GI2NxBgL2kKR
fdEOUGOlgnglXlPY25cILDaoQ9yaw+LJY+Vmu5NJ73cnkfejYVnMJYtcYjFQhPSx
0zuQspROQAJCFmXWj7VH5MTysfuVjjn0GWbGfJIOXw+/1LKdIuebgG8TACXZItXR
1S3P4VU7c5DR0a7vqohCrGDRwBZDDMg4FhKPel9rkyUwHpN4ur9EZscq8N8FJDY=
=9uIf
-----END PGP SIGNATURE-----