Hello,
Just completed a classic upgrade on a new server. NT4 style PDC was
running 4.6.5, new server running 4.13.0 (Debian Buster compiled from
source)
Classic Upgrade process seemed to go fine with one caveat, not sure if
it means anything - the wiki shows the upgrade process ending with:
==========================Commiting 'add users to groups' transaction to
disk
Setting password for administrator
==========================and mine ended with the 2nd to last line, that is
"Setting password
for administrator" did not show up.
While samba is trying to start I see a lot of processes:
==========================3388 ? Ss 0:00 samba: root process .
3389 ? S 0:00 samba: tfork waiter process(3390)
3390 ? S 0:00 samba: task[s3fs] pre-fork master
3391 ? S 0:00 samba: tfork waiter process(3393)
3392 ? S 0:00 samba: tfork waiter process(3394)
3393 ? S 0:00 samba: task[rpc] pre-fork master
3394 ? Ss 0:00 /usr/local/samba/sbin/smbd -D --option=server role
check:inhibit=yes --foreground
3395 ? S 0:00 samba: tfork waiter process(3396)
3396 ? S 0:00 samba: task[nbt] pre-fork master
3397 ? S 0:00 samba: tfork waiter process(3399)
3398 ? S 0:00 samba: tfork waiter process(3400)
3399 ? S 0:00 samba: task[rpc] pre-forked worker(0)
3400 ? S 0:00 samba: task[wrepl] pre-fork master
3401 ? S 0:00 samba: tfork waiter process(3403)
3402 ? S 0:00 samba: tfork waiter process(3404)
3403 ? S 0:00 samba: task[rpc] pre-forked worker(1)
3404 ? S 0:00 samba: task[ldap] pre-fork master
3405 ? S 0:00 samba: tfork waiter process(3407)
3406 ? S 0:00 samba: tfork waiter process(3408)
3407 ? S 0:00 samba: task[rpc] pre-forked worker(2)
3408 ? S 0:00 samba: task[cldap] pre-fork master
3409 ? S 0:00 samba: tfork waiter process(3411)
3410 ? S 0:00 samba: tfork waiter process(3412)
3411 ? S 0:00 samba: task[rpc] pre-forked worker(3)
3412 ? S 0:00 samba: task[kdc] pre-fork master
3413 ? S 0:00 samba: tfork waiter process(3414)
3414 ? S 0:00 samba: task[drepl] pre-fork master
3415 ? S 0:00 samba: tfork waiter process(3416)
3416 ? S 0:00 samba: task[kdc] pre-forked worker(0)
3417 ? S 0:00 samba: tfork waiter process(3418)
3418 ? S 0:00 samba: task[winbindd] pre-fork master
3419 ? S 0:00 samba: tfork waiter process(3422)
3420 ? S 0:00 samba: tfork waiter process(3423)
3421 ? S 0:00 samba: tfork waiter process(3424)
3422 ? S 0:00 samba: task[kdc] pre-forked worker(1)
3423 ? S 0:00 samba: task[ntp_signd] pre-fork master
3424 ? Ss 0:00 /usr/local/samba/sbin/winbindd -D --option=server
role check:inhibit=yes --foreground
3425 ? S 0:00 samba: tfork waiter process(3427)
3426 ? S 0:00 samba: tfork waiter process(3428)
3427 ? S 0:00 samba: task[kdc] pre-forked worker(2)
3428 ? S 0:00 samba: task[kcc] pre-fork master
3429 ? S 0:00 samba: tfork waiter process(3431)
3430 ? S 0:00 samba: tfork waiter process(3432)
3431 ? S 0:00 samba: task[kdc] pre-forked worker(3)
3432 ? S 0:00 samba: task[dnsupdate] pre-fork master
3439 ? S 0:00 /usr/local/samba/sbin/smbd -D --option=server role
check:inhibit=yes --foreground
3440 ? S 0:00 /usr/local/samba/sbin/smbd -D --option=server role
check:inhibit=yes --foreground
3441 ? S 0:00 /usr/local/samba/sbin/smbd -D --option=server role
check:inhibit=yes --foreground
3442 ? S 0:00 samba: tfork waiter process(3443)
3443 ? S 0:00 samba: task[ldap] pre-forked worker(0)
3444 ? S 0:00 samba: tfork waiter process(3445)
3445 ? S 0:00 samba: task[ldap] pre-forked worker(1)
3446 ? S 0:00 samba: tfork waiter process(3447)
3447 ? S 0:00 samba: task[ldap] pre-forked worker(2)
3448 ? S 0:00 samba: tfork waiter process(3449)
3449 ? S 0:00 samba: task[ldap] pre-forked worker(3)
==========================It eventually times out with:
==========================systemctl status samba.service
? samba.service - Samba AD Daemon
Loaded: loaded (/etc/systemd/system/samba.service; disabled; vendor preset:
enabled)
Active: failed (Result: timeout) since Sun 2020-10-18 16:47:39 UTC; 10s ago
Docs: man:samba(8)
man:samba(7)
man:smb.conf(5)
Process: 3479 ExecStart=/usr/local/samba/sbin/samba --foreground
--no-process-group $SAMBAOPTIONS (code=exited, status=127)
Main PID: 3479 (code=exited, status=127)
Oct 18 16:46:09 maxwell systemd[1]: samba.service: Got notification message from
PID 3515, but reception only permitted for main PID 3479
Oct 18 16:46:09 maxwell smbd[3485]: [2020/10/18 16:46:09.636690, 0]
../../lib/util/become_daemon.c:136(daemon_ready)
Oct 18 16:46:09 maxwell systemd[1]: samba.service: Got notification message from
PID 3485, but reception only permitted for main PID 3479
Oct 18 16:46:09 maxwell smbd[3485]: daemon_ready: daemon 'smbd'
finished
starting up and ready to serve connections
Oct 18 16:47:39 maxwell systemd[1]: samba.service: Start operation timed out.
Terminating.
Oct 18 16:47:39 maxwell systemd[1]: samba.service: Main process exited,
code=exited, status=127/n/a
Oct 18 16:47:39 maxwell winbindd[3515]: [2020/10/18 16:47:39.294870, 0]
../../source3/winbindd/winbindd.c:248(winbindd_sig_term_handler)
Oct 18 16:47:39 maxwell winbindd[3515]: Got sig[15] terminate (is_parent=1)
Oct 18 16:47:39 maxwell systemd[1]: samba.service: Failed with result
'timeout'.
Oct 18 16:47:39 maxwell systemd[1]: Failed to start Samba AD Daemon.
==========================from journalctl -xe :
========================== - The job identifier is 1040.
Oct 18 16:46:09 maxwell samba[3479]: [2020/10/18 16:46:09.198426, 0]
../../source4/smbd/server.c:631(binary_smbd_main)
Oct 18 16:46:09 maxwell samba[3479]: samba version 4.13.0 started.
Oct 18 16:46:09 maxwell samba[3479]: Copyright Andrew Tridgell and the Samba
Team 1992-2020
Oct 18 16:46:09 maxwell samba[3479]: [2020/10/18 16:46:09.372395, 0]
../../source4/smbd/server.c:872(binary_smbd_main)
Oct 18 16:46:09 maxwell samba[3479]: binary_smbd_main: samba: using
'prefork'
process model
Oct 18 16:46:09 maxwell systemd[1]: samba.service: Got notification message from
PID 3485, but reception only permitted for main PID 3479
Oct 18 16:46:09 maxwell systemd[1]: samba.service: Got notification message from
PID 3515, but reception only permitted for main PID 3479
Oct 18 16:46:09 maxwell samba[3523]: [2020/10/18 16:46:09.560696, 0]
../../lib/util/util_runcmd.c:352(samba_runcmd_io_handler)
Oct 18 16:46:09 maxwell samba[3523]: /usr/local/samba/sbin/samba_dnsupdate:
Traceback (most recent call last):
Oct 18 16:46:09 maxwell samba[3523]: [2020/10/18 16:46:09.560878, 0]
../../lib/util/util_runcmd.c:352(samba_runcmd_io_handler)
Oct 18 16:46:09 maxwell samba[3523]: /usr/local/samba/sbin/samba_dnsupdate:
File "/usr/local/samba/sbin/samba_dnsupdate", line 56, in
<module>
Oct 18 16:46:09 maxwell samba[3523]: [2020/10/18 16:46:09.560951, 0]
../../lib/util/util_runcmd.c:352(samba_runcmd_io_handler)
Oct 18 16:46:09 maxwell samba[3523]:
/usr/local/samba/sbin/samba_dnsupdate:
import dns.resolver
Oct 18 16:46:09 maxwell samba[3523]: [2020/10/18 16:46:09.560991, 0]
../../lib/util/util_runcmd.c:352(samba_runcmd_io_handler)
Oct 18 16:46:09 maxwell samba[3523]: /usr/local/samba/sbin/samba_dnsupdate:
ModuleNotFoundError: No module named 'dns'
Oct 18 16:46:09 maxwell samba[3523]: [2020/10/18 16:46:09.572867, 0]
../../source4/dsdb/dns/dns_update.c:86(dnsupdate_nameupdate_done)
Oct 18 16:46:09 maxwell samba[3523]: dnsupdate_nameupdate_done: Failed DNS
update with exit code 1
Oct 18 16:46:09 maxwell winbindd[3515]: [2020/10/18 16:46:09.629368, 0]
../../source3/winbindd/winbindd_cache.c:3205(initialize_winbindd_cache)
Oct 18 16:46:09 maxwell winbindd[3515]: initialize_winbindd_cache: clearing
cache and re-creating with version number 2
Oct 18 16:46:09 maxwell winbindd[3515]: [2020/10/18 16:46:09.631497, 0]
../../lib/util/become_daemon.c:136(daemon_ready)
Oct 18 16:46:09 maxwell winbindd[3515]: daemon_ready: daemon
'winbindd'
finished starting up and ready to serve connections
Oct 18 16:46:09 maxwell systemd[1]: samba.service: Got notification message from
PID 3515, but reception only permitted for main PID 3479
Oct 18 16:46:09 maxwell smbd[3485]: [2020/10/18 16:46:09.636690, 0]
../../lib/util/become_daemon.c:136(daemon_ready)
Oct 18 16:46:09 maxwell systemd[1]: samba.service: Got notification message from
PID 3485, but reception only permitted for main PID 3479
Oct 18 16:46:09 maxwell smbd[3485]: daemon_ready: daemon 'smbd'
finished
starting up and ready to serve connections
Oct 18 16:47:39 maxwell systemd[1]: samba.service: Start operation timed out.
Terminating.
Oct 18 16:47:39 maxwell systemd[1]: samba.service: Main process exited,
code=exited, status=127/n/a
-- Subject: Unit process exited
-- Defined-By: systemd
-- Support: https://www.debian.org/support
--
-- An ExecStart= process belonging to unit samba.service has exited.
--
-- The process' exit code is 'exited' and its exit status is 127.
Oct 18 16:47:39 maxwell winbindd[3515]: [2020/10/18 16:47:39.294870, 0]
../../source3/winbindd/winbindd.c:248(winbindd_sig_term_handler)
Oct 18 16:47:39 maxwell winbindd[3515]: Got sig[15] terminate (is_parent=1)
Oct 18 16:47:39 maxwell systemd[1]: samba.service: Failed with result
'timeout'.
-- Subject: Unit failed
-- Defined-By: systemd
-- Support: https://www.debian.org/support
--
-- The unit samba.service has entered the 'failed' state with result
'timeout'.
Oct 18 16:47:39 maxwell systemd[1]: Failed to start Samba AD Daemon.
-- Subject: A start job for unit samba.service has failed
-- Defined-By: systemd
-- Support: https://www.debian.org/support
--
-- A start job for unit samba.service has finished with a failure.
--
-- The job identifier is 1040 and the job result is failed
==========================
Any clues of how to fix this?
Thanks!
Chris