0s autopkgtest [15:57:14]: starting date and time: 2025-03-15 15:57:14+0000 0s autopkgtest [15:57:14]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [15:57:14]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.olodamge/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade nss-pam-ldapd --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-28.secgroup --name adt-plucky-s390x-nss-pam-ldapd-20250315-155714-juju-7f2275-prod-proposed-migration-environment-15-f174977a-51ae-4078-9a55-ba4b2f3f81b1 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-s390x -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 151s autopkgtest [15:59:45]: testbed dpkg architecture: s390x 151s autopkgtest [15:59:45]: testbed apt version: 2.9.33 152s autopkgtest [15:59:46]: @@@@@@@@@@@@@@@@@@@@ test bed setup 152s autopkgtest [15:59:46]: testbed release detected to be: None 152s autopkgtest [15:59:46]: updating testbed package index (apt update) 153s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 153s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 153s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 153s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 153s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 153s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 154s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 154s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [113 kB] 154s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1824 B] 154s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 154s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [320 kB] 154s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [13.4 kB] 154s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3776 B] 154s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [240 B] 155s Fetched 1073 kB in 2s (632 kB/s) 155s Reading package lists... 156s + lsb_release --codename --short 156s + RELEASE=plucky 156s + cat 156s + [ plucky != trusty ] 156s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 156s Reading package lists... 156s Building dependency tree... 156s Reading state information... 156s Calculating upgrade... 156s Calculating upgrade... 156s The following packages were automatically installed and are no longer required: 156s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 156s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 156s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 156s linux-tools-6.11.0-8-generic 156s Use 'sudo apt autoremove' to remove them. 156s The following packages will be upgraded: 156s pinentry-curses python3-jinja2 strace 156s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 156s Need to get 652 kB of archives. 156s After this operation, 27.6 kB of additional disk space will be used. 156s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB] 157s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x pinentry-curses s390x 1.3.1-2ubuntu3 [42.9 kB] 157s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 158s Fetched 652 kB in 1s (617 kB/s) 158s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 81428 files and directories currently installed.) 158s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ... 158s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 158s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_s390x.deb ... 158s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 158s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 158s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 158s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 158s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 158s Setting up strace (6.13+ds-1ubuntu1) ... 158s Processing triggers for man-db (2.13.0-1) ... 159s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 159s + /usr/lib/apt/apt-helper analyze-pattern ?true 159s + uname -r 159s + sed s/\./\\./g 159s + running_kernel_pattern=^linux-.*6\.14\.0-10-generic.* 159s + apt list ?obsolete 159s + + tail -n+2 159s cut -d/ -f1 159s + grep -v ^linux-.*6\.14\.0-10-generic.* 159s + obsolete_pkgs=linux-headers-6.11.0-8-generic 159s linux-headers-6.11.0-8 159s linux-modules-6.11.0-8-generic 159s linux-tools-6.11.0-8-generic 159s linux-tools-6.11.0-8 159s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove linux-headers-6.11.0-8-generic linux-headers-6.11.0-8 linux-modules-6.11.0-8-generic linux-tools-6.11.0-8-generic linux-tools-6.11.0-8 159s Reading package lists... 159s Building dependency tree... 159s Reading state information... 159s Solving dependencies... 159s The following packages will be REMOVED: 159s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 159s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 159s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 159s linux-tools-6.11.0-8-generic* 159s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded. 159s After this operation, 167 MB disk space will be freed. 159s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 81428 files and directories currently installed.) 159s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 159s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 159s Removing libpython3.12t64:s390x (3.12.9-1) ... 159s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 159s Removing libnsl2:s390x (1.3.0-3build3) ... 159s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 159s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 159s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 160s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 161s Processing triggers for libc-bin (2.41-1ubuntu1) ... 161s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56328 files and directories currently installed.) 161s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 161s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 161s + grep -q trusty /etc/lsb-release 161s + [ ! -d /usr/share/doc/unattended-upgrades ] 161s + [ ! -d /usr/share/doc/lxd ] 161s + [ ! -d /usr/share/doc/lxd-client ] 161s + [ ! -d /usr/share/doc/snapd ] 161s + type iptables 161s + cat 161s + chmod 755 /etc/rc.local 161s + . /etc/rc.local 161s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 161s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 161s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 161s + uname -m 161s + [ s390x = ppc64le ] 161s + [ -d /run/systemd/system ] 161s + systemd-detect-virt --quiet --vm 161s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 161s + cat 161s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 161s + echo COMPRESS=lz4 161s autopkgtest [15:59:55]: upgrading testbed (apt dist-upgrade and autopurge) 161s Reading package lists... 161s Building dependency tree... 161s Reading state information... 161s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 161s Starting 2 pkgProblemResolver with broken count: 0 161s Done 161s Entering ResolveByKeep 162s 162s Calculating upgrade... 162s The following packages will be upgraded: 162s libc-bin libc-dev-bin libc6 libc6-dev locales 162s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 162s Need to get 9512 kB of archives. 162s After this operation, 8192 B of additional disk space will be used. 162s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB] 164s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB] 164s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB] 167s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB] 168s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB] 173s Preconfiguring packages ... 174s Fetched 9512 kB in 12s (825 kB/s) 174s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56326 files and directories currently installed.) 174s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ... 174s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 174s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ... 174s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 174s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ... 174s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 174s Setting up libc6:s390x (2.41-1ubuntu2) ... 174s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56326 files and directories currently installed.) 174s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ... 174s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 174s Setting up libc-bin (2.41-1ubuntu2) ... 174s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56326 files and directories currently installed.) 174s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 174s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 174s Setting up locales (2.41-1ubuntu2) ... 174s Generating locales (this might take a while)... 176s en_US.UTF-8... done 176s Generation complete. 176s Setting up libc-dev-bin (2.41-1ubuntu2) ... 176s Setting up libc6-dev:s390x (2.41-1ubuntu2) ... 176s Processing triggers for man-db (2.13.0-1) ... 176s Processing triggers for systemd (257.3-1ubuntu3) ... 177s Reading package lists... 177s Building dependency tree... 177s Reading state information... 177s Starting pkgProblemResolver with broken count: 0 177s Starting 2 pkgProblemResolver with broken count: 0 177s Done 177s Solving dependencies... 178s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 178s autopkgtest [16:00:12]: rebooting testbed after setup commands that affected boot 198s autopkgtest [16:00:32]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP Wed Mar 12 14:53:49 UTC 2025 201s autopkgtest [16:00:35]: @@@@@@@@@@@@@@@@@@@@ apt-source nss-pam-ldapd 206s Get:1 http://ftpmaster.internal/ubuntu plucky/universe nss-pam-ldapd 0.9.13-1 (dsc) [2632 B] 206s Get:2 http://ftpmaster.internal/ubuntu plucky/universe nss-pam-ldapd 0.9.13-1 (tar) [808 kB] 206s Get:3 http://ftpmaster.internal/ubuntu plucky/universe nss-pam-ldapd 0.9.13-1 (asc) [866 B] 206s Get:4 http://ftpmaster.internal/ubuntu plucky/universe nss-pam-ldapd 0.9.13-1 (diff) [113 kB] 206s gpgv: Signature made Sun Feb 9 17:39:44 2025 UTC 206s gpgv: using RSA key 452EC2CB65CF68C2A1ADBF5F2A8B746810E0AFC1 206s gpgv: Can't check signature: No public key 206s dpkg-source: warning: cannot verify inline signature for ./nss-pam-ldapd_0.9.13-1.dsc: no acceptable signature found 206s autopkgtest [16:00:40]: testing package nss-pam-ldapd version 0.9.13-1 207s autopkgtest [16:00:41]: build not needed 207s autopkgtest [16:00:41]: test testsuite: preparing testbed 208s Reading package lists... 208s Building dependency tree... 208s Reading state information... 208s Starting pkgProblemResolver with broken count: 0 208s Starting 2 pkgProblemResolver with broken count: 0 208s Done 208s The following NEW packages will be installed: 208s expect ldap-utils libargon2-1 libltdl7 libnss-ldapd libodbc2 libpam-ldapd 208s libtcl8.6 nslcd slapd tcl-expect tcl8.6 208s 0 upgraded, 12 newly installed, 0 to remove and 0 not upgraded. 208s Need to get 3554 kB of archives. 208s After this operation, 12.1 MB of additional disk space will be used. 208s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libargon2-1 s390x 0~20190702+dfsg-4build1 [54.1 kB] 209s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libltdl7 s390x 2.5.4-4 [43.8 kB] 209s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libodbc2 s390x 2.3.12-2ubuntu1 [163 kB] 209s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x slapd s390x 2.6.9+dfsg-1~exp2ubuntu1 [1615 kB] 211s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x nslcd s390x 0.9.13-1 [165 kB] 211s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libtcl8.6 s390x 8.6.16+dfsg-1 [1034 kB] 212s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x tcl8.6 s390x 8.6.16+dfsg-1 [14.8 kB] 212s Get:8 http://ftpmaster.internal/ubuntu plucky/universe s390x tcl-expect s390x 5.45.4-4 [115 kB] 212s Get:9 http://ftpmaster.internal/ubuntu plucky/universe s390x expect s390x 5.45.4-4 [136 kB] 212s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x ldap-utils s390x 2.6.9+dfsg-1~exp2ubuntu1 [165 kB] 213s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x libnss-ldapd s390x 0.9.13-1 [30.0 kB] 213s Get:12 http://ftpmaster.internal/ubuntu plucky/universe s390x libpam-ldapd s390x 0.9.13-1 [17.1 kB] 213s Preconfiguring packages ... 213s Fetched 3554 kB in 4s (799 kB/s) 213s Selecting previously unselected package libargon2-1:s390x. 213s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56326 files and directories currently installed.) 213s Preparing to unpack .../00-libargon2-1_0~20190702+dfsg-4build1_s390x.deb ... 213s Unpacking libargon2-1:s390x (0~20190702+dfsg-4build1) ... 213s Selecting previously unselected package libltdl7:s390x. 213s Preparing to unpack .../01-libltdl7_2.5.4-4_s390x.deb ... 213s Unpacking libltdl7:s390x (2.5.4-4) ... 213s Selecting previously unselected package libodbc2:s390x. 213s Preparing to unpack .../02-libodbc2_2.3.12-2ubuntu1_s390x.deb ... 213s Unpacking libodbc2:s390x (2.3.12-2ubuntu1) ... 213s Selecting previously unselected package slapd. 213s Preparing to unpack .../03-slapd_2.6.9+dfsg-1~exp2ubuntu1_s390x.deb ... 213s Unpacking slapd (2.6.9+dfsg-1~exp2ubuntu1) ... 213s Selecting previously unselected package nslcd. 213s Preparing to unpack .../04-nslcd_0.9.13-1_s390x.deb ... 213s Unpacking nslcd (0.9.13-1) ... 213s Selecting previously unselected package libtcl8.6:s390x. 213s Preparing to unpack .../05-libtcl8.6_8.6.16+dfsg-1_s390x.deb ... 213s Unpacking libtcl8.6:s390x (8.6.16+dfsg-1) ... 213s Selecting previously unselected package tcl8.6. 213s Preparing to unpack .../06-tcl8.6_8.6.16+dfsg-1_s390x.deb ... 213s Unpacking tcl8.6 (8.6.16+dfsg-1) ... 213s Selecting previously unselected package tcl-expect:s390x. 213s Preparing to unpack .../07-tcl-expect_5.45.4-4_s390x.deb ... 213s Unpacking tcl-expect:s390x (5.45.4-4) ... 213s Selecting previously unselected package expect. 213s Preparing to unpack .../08-expect_5.45.4-4_s390x.deb ... 213s Unpacking expect (5.45.4-4) ... 213s Selecting previously unselected package ldap-utils. 213s Preparing to unpack .../09-ldap-utils_2.6.9+dfsg-1~exp2ubuntu1_s390x.deb ... 213s Unpacking ldap-utils (2.6.9+dfsg-1~exp2ubuntu1) ... 213s Selecting previously unselected package libnss-ldapd:s390x. 213s Preparing to unpack .../10-libnss-ldapd_0.9.13-1_s390x.deb ... 213s Unpacking libnss-ldapd:s390x (0.9.13-1) ... 213s Selecting previously unselected package libpam-ldapd:s390x. 213s Preparing to unpack .../11-libpam-ldapd_0.9.13-1_s390x.deb ... 213s Unpacking libpam-ldapd:s390x (0.9.13-1) ... 213s Setting up libargon2-1:s390x (0~20190702+dfsg-4build1) ... 213s Setting up ldap-utils (2.6.9+dfsg-1~exp2ubuntu1) ... 213s Setting up libtcl8.6:s390x (8.6.16+dfsg-1) ... 213s Setting up libltdl7:s390x (2.5.4-4) ... 213s Setting up libodbc2:s390x (2.3.12-2ubuntu1) ... 213s Setting up nslcd (0.9.13-1) ... 214s info: The home dir /run/nslcd you specified can't be accessed: No such file or directory 214s 214s info: Selecting UID from range 100 to 999 ... 214s 214s info: Selecting GID from range 100 to 999 ... 214s info: Adding system user `nslcd' (UID 107) ... 214s info: Adding new group `nslcd' (GID 110) ... 214s info: Adding new user `nslcd' (UID 107) with group `nslcd' ... 214s info: Not creating home directory `/run/nslcd'. 214s Created symlink '/etc/systemd/system/multi-user.target.wants/nslcd.service' -> '/usr/lib/systemd/system/nslcd.service'. 214s Setting up slapd (2.6.9+dfsg-1~exp2ubuntu1) ... 214s Creating new user openldap... done. 214s Creating initial configuration... done. 214s Creating LDAP directory... done. 215s Created symlink '/etc/systemd/system/multi-user.target.wants/slapd.service' → '/usr/lib/systemd/system/slapd.service'. 215s Setting up tcl8.6 (8.6.16+dfsg-1) ... 215s Setting up libpam-ldapd:s390x (0.9.13-1) ... 215s Setting up tcl-expect:s390x (5.45.4-4) ... 215s Setting up libnss-ldapd:s390x (0.9.13-1) ... 215s Setting up expect (5.45.4-4) ... 215s Processing triggers for man-db (2.13.0-1) ... 216s Processing triggers for libc-bin (2.41-1ubuntu2) ... 314s autopkgtest [16:02:28]: test testsuite: [----------------------- 314s testsuite: stopping services... 315s Failed to stop pynslcd.service: Unit pynslcd.service not loaded. 315s Failed to stop nscd.service: Unit nscd.service not loaded. 316s Failed to stop unscd.service: Unit unscd.service not loaded. 316s testsuite: making backup of old configuration... 316s testsuite: configuring /etc/nsswitch.conf... 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for passwd 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for group 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for shadow 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for hosts 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for networks 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for protocols 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for services 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for ethers 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for rpc 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for netgroup 316s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for aliases 316s testsuite: setting up test slapd... 316s Creating blank /tmp/slapd.1JwPDP slapd environment... done. 316s Loading cn=config... 316s added: "cn=config" (00000001) 316s added: "cn=module{0},cn=config" (00000001) 316s added: "cn=schema,cn=config" (00000001) 316s added: "cn={0}core,cn=schema,cn=config" (00000001) 316s added: "cn={1}cosine,cn=schema,cn=config" (00000001) 316s added: "cn={2}nis,cn=schema,cn=config" (00000001) 316s added: "cn={3}inetorgperson,cn=schema,cn=config" (00000001) 316s added: "cn={4}misc,cn=schema,cn=config" (00000001) 316s added: "cn={5}samba,cn=schema,cn=config" (00000001) 316s added: "cn={6}autofs,cn=schema,cn=config" (00000001) 316s added: "olcDatabase={-1}frontend,cn=config" (00000001) 316s added: "olcDatabase={0}config,cn=config" (00000001) 316s added: "olcDatabase={1}mdb,cn=config" (00000001) 316s added: "olcOverlay={0}ppolicy,olcDatabase={1}mdb,cn=config" (00000001) 316s Loading dc=test,dc=tld... 317s Fixing permissions... done. 317s Starting OpenLDAP: slapd. 317s testsuite: setting up nslcd.conf... 317s testsuite: starting nslcd... 318s testsuite: check test environment... 318s testenv.sh: nsswitch.conf configured for passwd group shadow hosts networks protocols services ethers rpc netgroup aliases 318s testenv.sh: LDAP server ldap://127.0.0.1/ providing dc=test,dc=tld 318s testenv.sh: nslcd running (pid 2293) 318s testenv.sh: test environment OK 318s testenv.sh: nsswitch.conf configured for passwd group shadow hosts networks protocols services ethers rpc netgroup aliases 318s testsuite: running NSS tests... 318s testenv.sh: nsswitch.conf configured for passwd group shadow hosts networks protocols services ethers rpc netgroup aliases 318s testenv.sh: LDAP server ldap://127.0.0.1/ providing dc=test,dc=tld 318s testenv.sh: nslcd running (pid 2293) 318s testenv.sh: test environment OK 318s test_nsscmds.sh: testing aliases... 318s test_nsscmds.sh: checking "getent aliases|sort" 318s test_nsscmds.sh: checking "getent aliases foo" 318s test_nsscmds.sh: checking "getent aliases bar2" 318s test_nsscmds.sh: checking "getent aliases FOO" 318s test_nsscmds.sh: testing ether... 318s test_nsscmds.sh: checking "getent ethers testhost" 318s test_nsscmds.sh: checking "getent ethers testhostalias" 318s test_nsscmds.sh: checking "getent ethers TESTHOST" 318s test_nsscmds.sh: checking "getent ethers 0:18:8a:54:1a:8b" 318s test_nsscmds.sh: checking "getent ethers" 318s test_nsscmds.sh: testing group... 318s test_nsscmds.sh: checking "getent group testgroup | sortgroup" 318s test_nsscmds.sh: checking "getent group TESTGROUP" 318s test_nsscmds.sh: checking "getent group 6100 | sortgroup" 318s test_nsscmds.sh: checking "groups testusr1 | sed 's/^.* *: *//'" 318s test_nsscmds.sh: checking "groups testuser4 | sed 's/^.* *: *//'" 318s test_nsscmds.sh: checking "getent group | egrep '^(testgroup|users|root):' | sortgroup" 318s test_nsscmds.sh: checking "getent group | wc -l" 318s test_nsscmds.sh: checking "getent group | grep ^largegroup | sortgroup" 318s test_nsscmds.sh: checking "getent group largegroup | sortgroup" 318s test_nsscmds.sh: checking "getent group | grep ^hugegroup | sortgroup" 318s test_nsscmds.sh: checking "getent group hugegroup | sortgroup" 318s test_nsscmds.sh: checking "getent group nstgrp1 | sortgroup" 318s test_nsscmds.sh: checking "getent group nstgrp2 | sortgroup" 318s test_nsscmds.sh: checking "getent group nstgrp3 | sortgroup" 318s test_nsscmds.sh: checking "groups testusr2 | sed 's/^.* *: *//'" 318s test_nsscmds.sh: checking "groups testusr3 | sed 's/^.* *: *//'" 318s test_nsscmds.sh: testing hosts... 318s test_nsscmds.sh: checking "getent hosts testhost" 318s test_nsscmds.sh: checking "getent hosts testhostalias" 318s test_nsscmds.sh: checking "getent hosts TESTHOST" 318s test_nsscmds.sh: checking "getent hosts 192.0.2.123" 318s test_nsscmds.sh: checking "getent hosts | grep testhost | sort" 318s test_nsscmds.sh: checking "getent hosts 2001:db8::dead:beef" 318s test_nsscmds.sh: checking "getent ahosts testhost2 | sed 's/ testhost2//' | sort" 318s test_nsscmds.sh: checking "getent hosts testhost3" 318s test_nsscmds.sh: checking "getent ahosts testhost3" 318s test_nsscmds.sh: checking "getent ahosts testhost4 | sed 's/ testhost4//' | sort" 318s test_nsscmds.sh: testing netgroup... 318s test_nsscmds.sh: checking "getent netgroup tstnetgroup" 318s test_nsscmds.sh: checking "getent netgroup TSTNETGROUP" 318s test_nsscmds.sh: testing networks... 318s test_nsscmds.sh: checking "getent networks testnet" 318s test_nsscmds.sh: checking "getent networks TESTNET" 318s test_nsscmds.sh: checking "getent networks 192.0.2.0" 318s test_nsscmds.sh: checking "getent networks | grep testnet" 318s test_nsscmds.sh: testing passwd... 318s test_nsscmds.sh: checking "getent passwd ecolden | sed 's/:[x*]:/:x:/'" 318s test_nsscmds.sh: checking "getent passwd testusr1 | sed 's/:[x*]:/:x:/'" 318s test_nsscmds.sh: checking "getent passwd TESTUSR1" 318s test_nsscmds.sh: checking "getent passwd 4089 | sed 's/:[x*]:/:x:/'" 318s test_nsscmds.sh: checking "getent passwd | grep -c ':[x*]:[45][0-9][0-9][0-9]:'" 319s test_nsscmds.sh: testing protocols... 319s test_nsscmds.sh: checking "getent protocols protfoo" 319s test_nsscmds.sh: checking "getent protocols protfooalias" 319s test_nsscmds.sh: checking "getent protocols PROTFOO" 319s test_nsscmds.sh: checking "getent protocols PROTFOOALIAS" 319s test_nsscmds.sh: checking "getent protocols 253" 319s test_nsscmds.sh: checking "getent protocols icmp" 319s test_nsscmds.sh: checking "getent protocols | grep protfoo" 319s test_nsscmds.sh: testing rpc... 319s test_nsscmds.sh: checking "getent rpc rpcfoo" 319s test_nsscmds.sh: checking "getent rpc rpcfooalias" 319s test_nsscmds.sh: checking "getent rpc RPCFOO" 319s test_nsscmds.sh: checking "getent rpc 160002" 319s test_nsscmds.sh: checking "getent rpc | grep rpcfoo" 319s test_nsscmds.sh: testing services... 319s test_nsscmds.sh: checking "getent services foosrv" 319s test_nsscmds.sh: checking "getent services foosrv/tcp" 319s test_nsscmds.sh: checking "getent services foosrv/udp" 319s test_nsscmds.sh: checking "getent services FOOSRV" 319s test_nsscmds.sh: checking "getent services foosrv/tCp" 319s test_nsscmds.sh: checking "getent services 15349/tcp" 319s test_nsscmds.sh: checking "getent services 15349/udp" 319s test_nsscmds.sh: checking "getent services barsrv" 319s test_nsscmds.sh: checking "getent services barsrv/tcp" 319s test_nsscmds.sh: checking "getent services barsrv/udp" 319s test_nsscmds.sh: checking "getent services | egrep '(foo|bar)srv' | sort" 319s test_nsscmds.sh: checking "getent services sssin" 319s test_nsscmds.sh: checking "getent services SSSIN" 319s test_nsscmds.sh: checking "getent services | wc -l" 319s test_nsscmds.sh: testing shadow... 319s test_nsscmds.sh: checking "getent shadow ecordas | rmpasswd" 319s test_nsscmds.sh: checking "getent shadow adishaw | rmpasswd" 319s test_nsscmds.sh: checking "getent shadow ADISHAW" 319s test_nsscmds.sh: checking "getent shadow | wc -l" 319s test_nsscmds.sh: checking "getent shadow | sed 's/:.*//' | sort" 319s test_nsscmds.sh: all tests passed 319s testsuite: running PAM tests... 319s testenv.sh: nsswitch.conf configured for passwd group shadow hosts networks protocols services ethers rpc netgroup aliases 319s testenv.sh: LDAP server ldap://127.0.0.1/ providing dc=test,dc=tld 319s testenv.sh: nslcd running (pid 2293) 319s testenv.sh: test environment OK 319s test_pamcmds.expect: resetting passwd... 319s test_pamcmds.expect: start shell... 319s test_pamcmds.expect: testing incorrect password... 321s test_pamcmds.expect: testing correct password... 322s test_pamcmds.expect: testing password change with incorrect password... 322s test_pamcmds.expect: testing password change with correct password... 322s test_pamcmds.expect: testing old password... 325s test_pamcmds.expect: testing new password... 325s test_pamcmds.expect: testing with unknown username... 325s test_pamcmds.expect: testing with root... 328s test_pamcmds.expect: testing with nobody... 331s test_pamcmds.expect: resetting passwd... 331s test_pamcmds.expect: everyting OK 331s testsuite: all tests passed! 331s testsuite: cleaning up... 332s Failed to stop pynslcd.service: Unit pynslcd.service not loaded. 332s Stopping OpenLDAP: slapd done. 332s Cleaning /tmp/slapd.1JwPDP... done. 332s testsuite: restoring configuration... 333s autopkgtest [16:02:47]: test testsuite: -----------------------] 349s autopkgtest [16:03:03]: test testsuite: - - - - - - - - - - results - - - - - - - - - - 349s testsuite PASS 361s autopkgtest [16:03:15]: test testsuite: preparing testbed 505s autopkgtest [16:05:39]: testbed dpkg architecture: s390x 505s autopkgtest [16:05:39]: testbed apt version: 2.9.33 505s autopkgtest [16:05:39]: @@@@@@@@@@@@@@@@@@@@ test bed setup 506s autopkgtest [16:05:40]: testbed release detected to be: plucky 507s autopkgtest [16:05:41]: updating testbed package index (apt update) 508s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 508s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 509s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 509s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 509s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 509s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 509s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 509s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [113 kB] 509s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1824 B] 509s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 509s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [320 kB] 510s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [13.4 kB] 510s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3776 B] 510s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [240 B] 510s Fetched 1073 kB in 2s (526 kB/s) 511s Reading package lists... 512s + lsb_release --codename --short 512s + RELEASE=plucky 512s + cat 512s + [ plucky != trusty ] 512s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 512s Reading package lists... 512s Building dependency tree... 512s Reading state information... 512s Calculating upgrade... 512s Calculating upgrade... 512s The following packages were automatically installed and are no longer required: 512s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 512s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 512s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 512s linux-tools-6.11.0-8-generic 512s Use 'sudo apt autoremove' to remove them. 512s The following packages will be upgraded: 512s pinentry-curses python3-jinja2 strace 512s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 512s Need to get 652 kB of archives. 512s After this operation, 27.6 kB of additional disk space will be used. 512s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB] 513s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x pinentry-curses s390x 1.3.1-2ubuntu3 [42.9 kB] 513s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 514s Fetched 652 kB in 1s (568 kB/s) 514s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 81428 files and directories currently installed.) 514s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ... 514s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 514s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_s390x.deb ... 514s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 514s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 514s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 514s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 514s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 514s Setting up strace (6.13+ds-1ubuntu1) ... 514s Processing triggers for man-db (2.13.0-1) ... 514s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 514s + /usr/lib/apt/apt-helper analyze-pattern ?true 514s + uname -r 514s + sed s/\./\\./g 514s + running_kernel_pattern=^linux-.*6\.14\.0-10-generic.* 514s + apt list ?obsolete 514s + + tail -n+2 514s cut -d/ -f1 515s + grep -v ^linux-.*6\.14\.0-10-generic.* 515s + obsolete_pkgs=linux-headers-6.11.0-8-generic 515s linux-headers-6.11.0-8 515s linux-modules-6.11.0-8-generic 515s linux-tools-6.11.0-8-generic 515s linux-tools-6.11.0-8 515s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove linux-headers-6.11.0-8-generic linux-headers-6.11.0-8 linux-modules-6.11.0-8-generic linux-tools-6.11.0-8-generic linux-tools-6.11.0-8 515s Reading package lists... 515s Building dependency tree... 515s Reading state information... 515s Solving dependencies... 515s The following packages will be REMOVED: 515s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 515s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 515s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 515s linux-tools-6.11.0-8-generic* 515s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded. 515s After this operation, 167 MB disk space will be freed. 515s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 81428 files and directories currently installed.) 515s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 515s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 515s Removing libpython3.12t64:s390x (3.12.9-1) ... 515s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 515s Removing libnsl2:s390x (1.3.0-3build3) ... 515s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 515s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 515s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 516s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 516s Processing triggers for libc-bin (2.41-1ubuntu1) ... 516s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56328 files and directories currently installed.) 516s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 516s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 516s + grep -q trusty /etc/lsb-release 516s + [ ! -d /usr/share/doc/unattended-upgrades ] 516s + [ ! -d /usr/share/doc/lxd ] 516s + [ ! -d /usr/share/doc/lxd-client ] 516s + [ ! -d /usr/share/doc/snapd ] 516s + type iptables 516s + cat 516s + chmod 755 /etc/rc.local 516s + . /etc/rc.local 516s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 516s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 516s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 516s + uname -m 516s + [ s390x = ppc64le ] 516s + [ -d /run/systemd/system ] 516s + systemd-detect-virt --quiet --vm 516s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 516s + cat 516s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 516s + echo COMPRESS=lz4 516s autopkgtest [16:05:50]: upgrading testbed (apt dist-upgrade and autopurge) 517s Reading package lists... 517s Building dependency tree... 517s Reading state information... 517s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 517s Starting 2 pkgProblemResolver with broken count: 0 517s Done 517s Entering ResolveByKeep 517s 517s Calculating upgrade... 517s The following packages will be upgraded: 517s libc-bin libc-dev-bin libc6 libc6-dev locales 518s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 518s Need to get 9512 kB of archives. 518s After this operation, 8192 B of additional disk space will be used. 518s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB] 520s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB] 520s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB] 524s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB] 525s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB] 530s Preconfiguring packages ... 530s Fetched 9512 kB in 13s (748 kB/s) 530s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56326 files and directories currently installed.) 530s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ... 530s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 530s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ... 530s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 531s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ... 531s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 531s Setting up libc6:s390x (2.41-1ubuntu2) ... 531s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56326 files and directories currently installed.) 531s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ... 531s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 531s Setting up libc-bin (2.41-1ubuntu2) ... 531s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56326 files and directories currently installed.) 531s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 531s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 531s Setting up locales (2.41-1ubuntu2) ... 531s Generating locales (this might take a while)... 532s en_US.UTF-8... done 532s Generation complete. 532s Setting up libc-dev-bin (2.41-1ubuntu2) ... 532s Setting up libc6-dev:s390x (2.41-1ubuntu2) ... 532s Processing triggers for man-db (2.13.0-1) ... 533s Processing triggers for systemd (257.3-1ubuntu3) ... 534s Reading package lists... 534s Building dependency tree... 534s Reading state information... 534s Starting pkgProblemResolver with broken count: 0 534s Starting 2 pkgProblemResolver with broken count: 0 534s Done 534s Solving dependencies... 534s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 534s autopkgtest [16:06:08]: rebooting testbed after setup commands that affected boot 556s Reading package lists... 556s Building dependency tree... 556s Reading state information... 556s Starting pkgProblemResolver with broken count: 0 556s Starting 2 pkgProblemResolver with broken count: 0 556s Done 557s The following NEW packages will be installed: 557s expect ldap-utils libargon2-1 libltdl7 libnss-ldapd libodbc2 libpam-ldapd 557s libtcl8.6 pynslcd python3-daemon python3-ldap python3-lockfile 557s python3-pyasn1 python3-pyasn1-modules python3-setproctitle slapd tcl-expect 557s tcl8.6 557s 0 upgraded, 18 newly installed, 0 to remove and 0 not upgraded. 557s Need to get 3783 kB of archives. 557s After this operation, 13.9 MB of additional disk space will be used. 557s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libargon2-1 s390x 0~20190702+dfsg-4build1 [54.1 kB] 557s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libltdl7 s390x 2.5.4-4 [43.8 kB] 557s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libodbc2 s390x 2.3.12-2ubuntu1 [163 kB] 557s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x slapd s390x 2.6.9+dfsg-1~exp2ubuntu1 [1615 kB] 559s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-lockfile all 1:0.12.2-4 [13.3 kB] 559s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-daemon all 3.1.2-2 [15.4 kB] 559s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1 all 0.6.1-1 [56.4 kB] 559s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1-modules all 0.4.1-2 [80.3 kB] 560s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x python3-ldap s390x 3.4.4-1build3 [93.0 kB] 560s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x python3-setproctitle s390x 1.3.5-1 [19.8 kB] 560s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x pynslcd all 0.9.13-1 [116 kB] 560s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libtcl8.6 s390x 8.6.16+dfsg-1 [1034 kB] 561s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x tcl8.6 s390x 8.6.16+dfsg-1 [14.8 kB] 561s Get:14 http://ftpmaster.internal/ubuntu plucky/universe s390x tcl-expect s390x 5.45.4-4 [115 kB] 561s Get:15 http://ftpmaster.internal/ubuntu plucky/universe s390x expect s390x 5.45.4-4 [136 kB] 561s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x ldap-utils s390x 2.6.9+dfsg-1~exp2ubuntu1 [165 kB] 562s Get:17 http://ftpmaster.internal/ubuntu plucky/universe s390x libnss-ldapd s390x 0.9.13-1 [30.0 kB] 562s Get:18 http://ftpmaster.internal/ubuntu plucky/universe s390x libpam-ldapd s390x 0.9.13-1 [17.1 kB] 562s Preconfiguring packages ... 562s Fetched 3783 kB in 5s (736 kB/s) 562s Selecting previously unselected package libargon2-1:s390x. 562s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 56326 files and directories currently installed.) 562s Preparing to unpack .../00-libargon2-1_0~20190702+dfsg-4build1_s390x.deb ... 562s Unpacking libargon2-1:s390x (0~20190702+dfsg-4build1) ... 562s Selecting previously unselected package libltdl7:s390x. 562s Preparing to unpack .../01-libltdl7_2.5.4-4_s390x.deb ... 562s Unpacking libltdl7:s390x (2.5.4-4) ... 562s Selecting previously unselected package libodbc2:s390x. 562s Preparing to unpack .../02-libodbc2_2.3.12-2ubuntu1_s390x.deb ... 562s Unpacking libodbc2:s390x (2.3.12-2ubuntu1) ... 562s Selecting previously unselected package slapd. 562s Preparing to unpack .../03-slapd_2.6.9+dfsg-1~exp2ubuntu1_s390x.deb ... 562s Unpacking slapd (2.6.9+dfsg-1~exp2ubuntu1) ... 562s Selecting previously unselected package python3-lockfile. 562s Preparing to unpack .../04-python3-lockfile_1%3a0.12.2-4_all.deb ... 562s Unpacking python3-lockfile (1:0.12.2-4) ... 562s Selecting previously unselected package python3-daemon. 562s Preparing to unpack .../05-python3-daemon_3.1.2-2_all.deb ... 562s Unpacking python3-daemon (3.1.2-2) ... 562s Selecting previously unselected package python3-pyasn1. 562s Preparing to unpack .../06-python3-pyasn1_0.6.1-1_all.deb ... 562s Unpacking python3-pyasn1 (0.6.1-1) ... 562s Selecting previously unselected package python3-pyasn1-modules. 562s Preparing to unpack .../07-python3-pyasn1-modules_0.4.1-2_all.deb ... 562s Unpacking python3-pyasn1-modules (0.4.1-2) ... 562s Selecting previously unselected package python3-ldap. 562s Preparing to unpack .../08-python3-ldap_3.4.4-1build3_s390x.deb ... 562s Unpacking python3-ldap (3.4.4-1build3) ... 562s Selecting previously unselected package python3-setproctitle:s390x. 562s Preparing to unpack .../09-python3-setproctitle_1.3.5-1_s390x.deb ... 562s Unpacking python3-setproctitle:s390x (1.3.5-1) ... 562s Selecting previously unselected package pynslcd. 562s Preparing to unpack .../10-pynslcd_0.9.13-1_all.deb ... 562s Unpacking pynslcd (0.9.13-1) ... 562s Selecting previously unselected package libtcl8.6:s390x. 562s Preparing to unpack .../11-libtcl8.6_8.6.16+dfsg-1_s390x.deb ... 562s Unpacking libtcl8.6:s390x (8.6.16+dfsg-1) ... 562s Selecting previously unselected package tcl8.6. 562s Preparing to unpack .../12-tcl8.6_8.6.16+dfsg-1_s390x.deb ... 562s Unpacking tcl8.6 (8.6.16+dfsg-1) ... 562s Selecting previously unselected package tcl-expect:s390x. 562s Preparing to unpack .../13-tcl-expect_5.45.4-4_s390x.deb ... 562s Unpacking tcl-expect:s390x (5.45.4-4) ... 562s Selecting previously unselected package expect. 562s Preparing to unpack .../14-expect_5.45.4-4_s390x.deb ... 562s Unpacking expect (5.45.4-4) ... 562s Selecting previously unselected package ldap-utils. 562s Preparing to unpack .../15-ldap-utils_2.6.9+dfsg-1~exp2ubuntu1_s390x.deb ... 562s Unpacking ldap-utils (2.6.9+dfsg-1~exp2ubuntu1) ... 562s Selecting previously unselected package libnss-ldapd:s390x. 562s Preparing to unpack .../16-libnss-ldapd_0.9.13-1_s390x.deb ... 562s Unpacking libnss-ldapd:s390x (0.9.13-1) ... 563s Selecting previously unselected package libpam-ldapd:s390x. 563s Preparing to unpack .../17-libpam-ldapd_0.9.13-1_s390x.deb ... 563s Unpacking libpam-ldapd:s390x (0.9.13-1) ... 563s Setting up python3-lockfile (1:0.12.2-4) ... 563s Setting up libargon2-1:s390x (0~20190702+dfsg-4build1) ... 563s Setting up ldap-utils (2.6.9+dfsg-1~exp2ubuntu1) ... 563s Setting up python3-setproctitle:s390x (1.3.5-1) ... 563s Setting up libtcl8.6:s390x (8.6.16+dfsg-1) ... 563s Setting up python3-daemon (3.1.2-2) ... 563s Setting up libltdl7:s390x (2.5.4-4) ... 563s Setting up libodbc2:s390x (2.3.12-2ubuntu1) ... 563s Setting up python3-pyasn1 (0.6.1-1) ... 563s Setting up slapd (2.6.9+dfsg-1~exp2ubuntu1) ... 563s Creating new user openldap... done. 563s Creating initial configuration... done. 563s Creating LDAP directory... done. 563s Created symlink '/etc/systemd/system/multi-user.target.wants/slapd.service' → '/usr/lib/systemd/system/slapd.service'. 564s Setting up tcl8.6 (8.6.16+dfsg-1) ... 564s Setting up tcl-expect:s390x (5.45.4-4) ... 564s Setting up python3-pyasn1-modules (0.4.1-2) ... 564s Setting up python3-ldap (3.4.4-1build3) ... 564s Setting up expect (5.45.4-4) ... 564s Setting up pynslcd (0.9.13-1) ... 564s info: The home dir /run/nslcd you specified can't be accessed: No such file or directory 564s 564s info: Selecting UID from range 100 to 999 ... 564s 564s info: Selecting GID from range 100 to 999 ... 564s info: Adding system user `nslcd' (UID 108) ... 564s info: Adding new group `nslcd' (GID 113) ... 564s info: Adding new user `nslcd' (UID 108) with group `nslcd' ... 564s info: Not creating home directory `/run/nslcd'. 564s Created symlink '/etc/systemd/system/multi-user.target.wants/pynslcd.service' -> '/usr/lib/systemd/system/pynslcd.service'. 565s Setting up libnss-ldapd:s390x (0.9.13-1) ... 565s Setting up libpam-ldapd:s390x (0.9.13-1) ... 565s Processing triggers for man-db (2.13.0-1) ... 566s Processing triggers for libc-bin (2.41-1ubuntu2) ... 571s autopkgtest [16:06:45]: test testsuite: [----------------------- 572s testsuite: stopping services... 572s Failed to stop nslcd.service: Unit nslcd.service not loaded. 573s Failed to stop nscd.service: Unit nscd.service not loaded. 574s Failed to stop unscd.service: Unit unscd.service not loaded. 574s testsuite: making backup of old configuration... 574s testsuite: configuring /etc/nsswitch.conf... 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for passwd 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for group 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for shadow 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for hosts 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for networks 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for protocols 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for services 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for ethers 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for rpc 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for netgroup 574s testenv.sh: /etc/nsswitch.conf: enable LDAP lookups for aliases 574s testsuite: setting up test slapd... 574s Creating blank /tmp/slapd.2FsnFw slapd environment... done. 574s Loading cn=config... 574s added: "cn=config" (00000001) 574s added: "cn=module{0},cn=config" (00000001) 574s added: "cn=schema,cn=config" (00000001) 574s added: "cn={0}core,cn=schema,cn=config" (00000001) 574s added: "cn={1}cosine,cn=schema,cn=config" (00000001) 574s added: "cn={2}nis,cn=schema,cn=config" (00000001) 574s added: "cn={3}inetorgperson,cn=schema,cn=config" (00000001) 574s added: "cn={4}misc,cn=schema,cn=config" (00000001) 574s added: "cn={5}samba,cn=schema,cn=config" (00000001) 574s added: "cn={6}autofs,cn=schema,cn=config" (00000001) 574s added: "olcDatabase={-1}frontend,cn=config" (00000001) 574s added: "olcDatabase={0}config,cn=config" (00000001) 574s added: "olcDatabase={1}mdb,cn=config" (00000001) 574s added: "olcOverlay={0}ppolicy,olcDatabase={1}mdb,cn=config" (00000001) 574s Loading dc=test,dc=tld... 575s Fixing permissions... done. 575s Starting OpenLDAP: slapd. 575s testsuite: setting up nslcd.conf... 575s testsuite: starting pynslcd... 576s testsuite: check test environment... 576s testenv.sh: nsswitch.conf configured for passwd group shadow hosts networks protocols services ethers rpc netgroup aliases 576s testenv.sh: LDAP server ldap://127.0.0.1/ providing dc=test,dc=tld 576s testenv.sh: nslcd running (pid 2211) 576s testenv.sh: test environment OK 576s testenv.sh: nsswitch.conf configured for passwd group shadow hosts networks protocols services ethers rpc netgroup aliases 576s testsuite: running NSS tests... 576s testenv.sh: nsswitch.conf configured for passwd group shadow hosts networks protocols services ethers rpc netgroup aliases 576s testenv.sh: LDAP server ldap://127.0.0.1/ providing dc=test,dc=tld 576s testenv.sh: nslcd running (pid 2211) 576s testenv.sh: test environment OK 576s test_nsscmds.sh: testing aliases... 576s test_nsscmds.sh: checking "getent aliases|sort" 576s test_nsscmds.sh: checking "getent aliases foo" 576s test_nsscmds.sh: checking "getent aliases bar2" 576s test_nsscmds.sh: checking "getent aliases FOO" 576s test_nsscmds.sh: testing ether... 576s test_nsscmds.sh: checking "getent ethers testhost" 576s test_nsscmds.sh: checking "getent ethers testhostalias" 576s test_nsscmds.sh: checking "getent ethers TESTHOST" 576s test_nsscmds.sh: checking "getent ethers 0:18:8a:54:1a:8b" 576s test_nsscmds.sh: checking "getent ethers" 576s test_nsscmds.sh: testing group... 576s test_nsscmds.sh: checking "getent group testgroup | sortgroup" 576s test_nsscmds.sh: checking "getent group TESTGROUP" 576s test_nsscmds.sh: checking "getent group 6100 | sortgroup" 576s test_nsscmds.sh: checking "groups testusr1 | sed 's/^.* *: *//'" 576s test_nsscmds.sh: checking "groups testuser4 | sed 's/^.* *: *//'" 576s test_nsscmds.sh: checking "getent group | egrep '^(testgroup|users|root):' | sortgroup" 576s test_nsscmds.sh: checking "getent group | wc -l" 576s test_nsscmds.sh: checking "getent group | grep ^largegroup | sortgroup" 576s test_nsscmds.sh: checking "getent group largegroup | sortgroup" 576s test_nsscmds.sh: checking "getent group | grep ^hugegroup | sortgroup" 576s test_nsscmds.sh: checking "getent group hugegroup | sortgroup" 576s test_nsscmds.sh: checking "getent group nstgrp1 | sortgroup" 576s test_nsscmds.sh: checking "getent group nstgrp2 | sortgroup" 576s test_nsscmds.sh: checking "getent group nstgrp3 | sortgroup" 576s test_nsscmds.sh: checking "groups testusr2 | sed 's/^.* *: *//'" 576s test_nsscmds.sh: checking "groups testusr3 | sed 's/^.* *: *//'" 576s test_nsscmds.sh: testing hosts... 576s test_nsscmds.sh: checking "getent hosts testhost" 576s test_nsscmds.sh: checking "getent hosts testhostalias" 576s test_nsscmds.sh: checking "getent hosts TESTHOST" 576s test_nsscmds.sh: checking "getent hosts 192.0.2.123" 576s test_nsscmds.sh: checking "getent hosts | grep testhost | sort" 576s test_nsscmds.sh: checking "getent hosts 2001:db8::dead:beef" 576s test_nsscmds.sh: checking "getent ahosts testhost2 | sed 's/ testhost2//' | sort" 576s test_nsscmds.sh: checking "getent hosts testhost3" 576s test_nsscmds.sh: checking "getent ahosts testhost3" 576s test_nsscmds.sh: checking "getent ahosts testhost4 | sed 's/ testhost4//' | sort" 576s test_nsscmds.sh: testing netgroup... 576s test_nsscmds.sh: checking "getent netgroup tstnetgroup" 576s test_nsscmds.sh: checking "getent netgroup TSTNETGROUP" 576s test_nsscmds.sh: testing networks... 576s test_nsscmds.sh: checking "getent networks testnet" 576s test_nsscmds.sh: checking "getent networks TESTNET" 576s test_nsscmds.sh: checking "getent networks 192.0.2.0" 576s test_nsscmds.sh: checking "getent networks | grep testnet" 576s test_nsscmds.sh: testing passwd... 576s test_nsscmds.sh: checking "getent passwd ecolden | sed 's/:[x*]:/:x:/'" 576s test_nsscmds.sh: checking "getent passwd testusr1 | sed 's/:[x*]:/:x:/'" 576s test_nsscmds.sh: checking "getent passwd TESTUSR1" 576s test_nsscmds.sh: checking "getent passwd 4089 | sed 's/:[x*]:/:x:/'" 576s test_nsscmds.sh: checking "getent passwd | grep -c ':[x*]:[45][0-9][0-9][0-9]:'" 576s test_nsscmds.sh: testing protocols... 576s test_nsscmds.sh: checking "getent protocols protfoo" 576s test_nsscmds.sh: checking "getent protocols protfooalias" 576s test_nsscmds.sh: checking "getent protocols PROTFOO" 576s test_nsscmds.sh: checking "getent protocols PROTFOOALIAS" 576s test_nsscmds.sh: checking "getent protocols 253" 576s test_nsscmds.sh: checking "getent protocols icmp" 576s test_nsscmds.sh: checking "getent protocols | grep protfoo" 576s test_nsscmds.sh: testing rpc... 576s test_nsscmds.sh: checking "getent rpc rpcfoo" 576s test_nsscmds.sh: checking "getent rpc rpcfooalias" 576s test_nsscmds.sh: checking "getent rpc RPCFOO" 576s test_nsscmds.sh: checking "getent rpc 160002" 576s test_nsscmds.sh: checking "getent rpc | grep rpcfoo" 576s test_nsscmds.sh: testing services... 576s test_nsscmds.sh: checking "getent services foosrv" 576s test_nsscmds.sh: checking "getent services foosrv/tcp" 576s test_nsscmds.sh: checking "getent services foosrv/udp" 576s test_nsscmds.sh: checking "getent services FOOSRV" 576s test_nsscmds.sh: checking "getent services foosrv/tCp" 576s test_nsscmds.sh: checking "getent services 15349/tcp" 576s test_nsscmds.sh: checking "getent services 15349/udp" 576s test_nsscmds.sh: checking "getent services barsrv" 576s test_nsscmds.sh: checking "getent services barsrv/tcp" 576s test_nsscmds.sh: checking "getent services barsrv/udp" 576s test_nsscmds.sh: checking "getent services | egrep '(foo|bar)srv' | sort" 576s test_nsscmds.sh: checking "getent services sssin" 576s test_nsscmds.sh: checking "getent services SSSIN" 576s test_nsscmds.sh: checking "getent services | wc -l" 576s test_nsscmds.sh: testing shadow... 576s test_nsscmds.sh: checking "getent shadow ecordas | rmpasswd" 576s test_nsscmds.sh: checking "getent shadow adishaw | rmpasswd" 576s test_nsscmds.sh: checking "getent shadow ADISHAW" 577s test_nsscmds.sh: checking "getent shadow | wc -l" 577s test_nsscmds.sh: checking "getent shadow | sed 's/:.*//' | sort" 577s test_nsscmds.sh: all tests passed 577s testsuite: running PAM tests... 577s testenv.sh: nsswitch.conf configured for passwd group shadow hosts networks protocols services ethers rpc netgroup aliases 577s testenv.sh: LDAP server ldap://127.0.0.1/ providing dc=test,dc=tld 577s testenv.sh: nslcd running (pid 2211) 577s testenv.sh: test environment OK 577s test_pamcmds.expect: resetting passwd... 577s test_pamcmds.expect: start shell... 577s test_pamcmds.expect: testing incorrect password... 580s test_pamcmds.expect: testing correct password... 580s test_pamcmds.expect: testing password change with incorrect password... 580s test_pamcmds.expect: testing password change with correct password... 580s 580s 580s test_pamcmds.expect: ERROR found: 580s 580s Authentication service cannot retrieve authentication info 580s passwd: Authentication token manipulation error 580s testsuite: FAIL 580s testsuite: pynslcd debug output: 581s pynslcd: DEBUG: NSS_LDAP b'nss-pam-ldapd' b'0.9.13' 581s pynslcd: DEBUG: logging= 581s pynslcd: DEBUG: re= 581s pynslcd: DEBUG: ldap= 581s pynslcd: DEBUG: threads=5 581s pynslcd: DEBUG: uid=None 581s pynslcd: DEBUG: gid=None 581s pynslcd: DEBUG: logs=[('syslog', 20)] 581s pynslcd: DEBUG: uri='ldap://127.0.0.1/' 581s pynslcd: DEBUG: ldap_version=3 581s pynslcd: DEBUG: binddn=None 581s pynslcd: DEBUG: bindpw=None 581s pynslcd: DEBUG: rootpwmoddn='cn=admin,dc=test,dc=tld' 581s pynslcd: DEBUG: rootpwmodpw='test' 581s pynslcd: DEBUG: sasl_mech=None 581s pynslcd: DEBUG: sasl_realm=None 581s pynslcd: DEBUG: sasl_authcid=None 581s pynslcd: DEBUG: sasl_authzid=None 581s pynslcd: DEBUG: sasl_secprops=None 581s pynslcd: DEBUG: sasl_canonicalize=None 581s pynslcd: DEBUG: bases=['dc=test,dc=tld'] 581s pynslcd: DEBUG: scope=2 581s pynslcd: DEBUG: deref=0 581s pynslcd: DEBUG: referrals=True 581s pynslcd: DEBUG: bind_timelimit=4 581s pynslcd: DEBUG: timelimit=2 581s pynslcd: DEBUG: idle_timelimit=0 581s pynslcd: DEBUG: reconnect_sleeptime=4 581s pynslcd: DEBUG: reconnect_retrytime=10 581s pynslcd: DEBUG: ssl=None 581s pynslcd: DEBUG: tls_reqcert=None 581s pynslcd: DEBUG: tls_cacertdir=None 581s pynslcd: DEBUG: tls_cacertfile=None 581s pynslcd: DEBUG: tls_randfile=None 581s pynslcd: DEBUG: tls_ciphers=None 581s pynslcd: DEBUG: tls_cert=None 581s pynslcd: DEBUG: tls_key=None 581s pynslcd: DEBUG: pagesize=0 581s pynslcd: DEBUG: nss_initgroups_ignoreusers=set() 581s pynslcd: DEBUG: nss_min_uid=0 581s pynslcd: DEBUG: nss_uid_offset=0 581s pynslcd: DEBUG: nss_gid_offset=0 581s pynslcd: DEBUG: nss_nested_groups=True 581s pynslcd: DEBUG: nss_getgrent_skipmembers=False 581s pynslcd: DEBUG: nss_disable_enumeration=False 581s pynslcd: DEBUG: validnames=re.compile('^[a-z0-9._@$][a-z0-9._@$ \\\\~-]{0,98}[a-z0-9._@$~-]$', re.IGNORECASE) 581s pynslcd: DEBUG: pam_authc_ppolicy=True 581s pynslcd: DEBUG: pam_authz_searches=[] 581s pynslcd: DEBUG: pam_password_prohibit_message=None 581s pynslcd: DEBUG: reconnect_invalidate={'nfsidmap', 'group', 'passwd'} 581s pynslcd: DEBUG: ParseError= 581s pynslcd: DEBUG: read= 581s pynslcd: DEBUG: get_usergid= 581s pynslcd: version 0.9.13 starting 581s pynslcd: accepting connections 581s /usr/sbin/pynslcd:370: DeprecationWarning: setDaemon() is deprecated, set the daemon attribute instead 581s thread.setDaemon(True) 581s /usr/sbin/pynslcd:372: DeprecationWarning: getName() is deprecated, get the name attribute instead 581s logging.debug('started thread %s', thread.getName()) 581s pynslcd: DEBUG: started thread thread0 581s pynslcd: DEBUG: started thread thread1 581s pynslcd: DEBUG: started thread thread2 581s pynslcd: DEBUG: started thread thread3 581s pynslcd: DEBUG: started thread thread4 581s pynslcd: DEBUG: connection from pid=2294 uid=0 gid=0 581s pynslcd: DEBUG: AliasAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(objectClass=nisMailAlias)') 581s pynslcd: connected to LDAP server ldap://127.0.0.1/ 581s pynslcd: DEBUG: invalidator: nfsidmap -c 581s pynslcd: DEBUG: connection from pid=2304 uid=0 gid=0 581s pynslcd: DEBUG: AliasByNameRequest({'cn': 'foo'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=nisMailAlias)(cn=foo))') 581s pynslcd: invalidator: nfsidmap -c failed 581s Traceback (most recent call last): 581s File "/usr/share/pynslcd/invalidator.py", line 46, in exec_invalidate 581s p = subprocess.Popen(args, bufsize=4096, close_fds=True, 581s stdout=subprocess.PIPE, stderr=subprocess.STDOUT) 581s File "/usr/lib/python3.13/subprocess.py", line 1036, in __init__ 581s self._execute_child(args, executable, preexec_fn, close_fds, 581s ~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s pass_fds, cwd, env, 581s ^^^^^^^^^^^^^^^^^^^ 581s ...<5 lines>... 581s gid, gids, uid, umask, 581s ^^^^^^^^^^^^^^^^^^^^^^ 581s start_new_session, process_group) 581s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3.13/subprocess.py", line 1966, in _execute_child 581s raise child_exception_type(errno_num, err_msg, err_filename) 581s FileNotFoundError: [Errno 2] No such file or directory: 'nfsidmap' 581s pynslcd: DEBUG: invalidator: nscd -i group 581s pynslcd: invalidator: nscd -i group failed 581s Traceback (most recent call last): 581s File "/usr/share/pynslcd/invalidator.py", line 46, in exec_invalidate 581s p = subprocess.Popen(args, bufsize=4096, close_fds=True, 581s stdout=subprocess.PIPE, stderr=subprocess.STDOUT) 581s File "/usr/lib/python3.13/subprocess.py", line 1036, in __init__ 581s self._execute_child(args, executable, preexec_fn, close_fds, 581s ~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s pass_fds, cwd, env, 581s ^^^^^^^^^^^^^^^^^^^ 581s ...<5 lines>... 581s gid, gids, uid, umask, 581s ^^^^^^^^^^^^^^^^^^^^^^ 581s start_new_session, process_group) 581s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3.13/subprocess.py", line 1966, in _execute_child 581s raise child_exception_type(errno_num, err_msg, err_filename) 581s FileNotFoundError: [Errno 2] No such file or directory: 'nscd' 581s pynslcd: DEBUG: invalidator: nscd -i passwd 581s pynslcd: invalidator: nscd -i passwd failed 581s Traceback (most recent call last): 581s File "/usr/share/pynslcd/invalidator.py", line 46, in exec_invalidate 581s p = subprocess.Popen(args, bufsize=4096, close_fds=True, 581s stdout=subprocess.PIPE, stderr=subprocess.STDOUT) 581s File "/usr/lib/python3.13/subprocess.py", line 1036, in __init__ 581s self._execute_child(args, executable, preexec_fn, close_fds, 581s ~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s pass_fds, cwd, env, 581s ^^^^^^^^^^^^^^^^^^^ 581s ...<5 lines>... 581s gid, gids, uid, umask, 581s ^^^^^^^^^^^^^^^^^^^^^^ 581s start_new_session, process_group) 581s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3.13/subprocess.py", line 1966, in _execute_child 581s raise child_exception_type(errno_num, err_msg, err_filename) 581s FileNotFoundError: [Errno 2] No such file or directory: 'nscd' 581s pynslcd: DEBUG: connection from pid=2314 uid=0 gid=0 581s pynslcd: DEBUG: AliasByNameRequest({'cn': 'bar2'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=nisMailAlias)(cn=bar2))') 581s pynslcd: DEBUG: connection from pid=2322 uid=0 gid=0 581s pynslcd: DEBUG: AliasByNameRequest({'cn': 'FOO'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=nisMailAlias)(cn=FOO))') 581s pynslcd: DEBUG: connection from pid=2331 uid=0 gid=0 581s pynslcd: DEBUG: EtherByNameRequest({'cn': 'testhost'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ieee802Device)(cn=testhost))') 581s pynslcd: DEBUG: connection from pid=2339 uid=0 gid=0 581s pynslcd: DEBUG: EtherByNameRequest({'cn': 'testhostalias'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ieee802Device)(cn=testhostalias))') 581s pynslcd: DEBUG: connection from pid=2348 uid=0 gid=0 581s pynslcd: DEBUG: EtherByNameRequest({'cn': 'TESTHOST'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ieee802Device)(cn=TESTHOST))') 581s pynslcd: DEBUG: connection from pid=2356 uid=0 gid=0 581s pynslcd: DEBUG: EtherByEtherRequest({'macAddress': '0:18:8a:54:1a:8b'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ieee802Device)(|(macAddress=0:18:8a:54:1a:8b)(macAddress=00:18:8a:54:1a:8b)))') 581s pynslcd: DEBUG: connection from pid=2373 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'testgroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=testgroup))') 581s pynslcd: DEBUG: connection from pid=2394 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'TESTGROUP'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=TESTGROUP))') 581s pynslcd: DEBUG: cn=testgroup,ou=groups,dc=test,dc=tld: cn: does not contain 'TESTGROUP' value 581s pynslcd: DEBUG: connection from pid=2402 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6100}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6100))') 581s pynslcd: DEBUG: connection from pid=2402 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6100}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6100))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'testusr1'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testusr1))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByUidRequest({'uidNumber': 1007}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uidNumber=1007))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByMemberRequest({'memberUid': 'testusr1'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testusr1))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(|(memberUid=testusr1)(member=uid=testusr1,ou=people,dc=test,dc=tld)))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp12,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=testgroup,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp8,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp13,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp9,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp10,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp4,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp6,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp16,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=testgroup2,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp17,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp18,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp7,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp15,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=users,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp14,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp11,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp5,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByMemberRequest({'memberUid': 'testusr1'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testusr1))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(|(memberUid=testusr1)(member=uid=testusr1,ou=people,dc=test,dc=tld)))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp12,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=testgroup,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp8,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp13,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp9,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp10,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp4,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp6,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp16,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=testgroup2,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp17,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp18,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp7,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp15,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=users,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp14,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp11,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=grp5,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6100}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6100))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6100}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6100))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6200}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6200))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6200}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6200))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 704}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=704))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 704}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=704))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 705}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=705))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 705}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=705))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 706}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=706))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 706}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=706))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 707}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=707))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 707}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=707))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 708}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=708))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 708}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=708))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 709}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=709))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 709}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=709))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 710}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=710))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 710}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=710))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 711}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=711))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 711}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=711))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 712}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=712))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 712}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=712))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 713}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=713))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 713}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=713))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 714}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=714))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 714}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=714))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 715}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=715))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 715}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=715))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 716}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=716))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 716}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=716))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 717}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=717))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 717}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=717))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 718}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=718))') 581s pynslcd: DEBUG: connection from pid=2423 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 718}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=718))') 581s pynslcd: DEBUG: connection from pid=2432 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'testuser4'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testuser4))') 581s pynslcd: DEBUG: connection from pid=2432 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByUidRequest({'uidNumber': 1004}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uidNumber=1004))') 581s pynslcd: DEBUG: connection from pid=2432 uid=0 gid=0 581s pynslcd: DEBUG: GroupByMemberRequest({'memberUid': 'testuser4'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testuser4))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(|(memberUid=testuser4)(member=cn=Test\\5c2C User4,ou=people,dc=test,dc=tld)))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=testgroup2,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=testgroup,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: connection from pid=2432 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6100}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6100))') 581s pynslcd: DEBUG: connection from pid=2432 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6100}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6100))') 581s pynslcd: DEBUG: connection from pid=2432 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6200}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6200))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2432 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6200}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6200))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2442 uid=0 gid=0 581s pynslcd: DEBUG: GroupAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2503 uid=0 gid=0 581s pynslcd: DEBUG: GroupAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2512 uid=0 gid=0 581s pynslcd: DEBUG: GroupAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2534 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'largegroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=largegroup))') 581s pynslcd: DEBUG: connection from pid=2534 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'largegroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=largegroup))') 581s pynslcd: DEBUG: connection from pid=2556 uid=0 gid=0 581s pynslcd: DEBUG: GroupAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2579 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'hugegroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=hugegroup))') 581s pynslcd: DEBUG: connection from pid=2579 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'hugegroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=hugegroup))') 581s pynslcd: DEBUG: connection from pid=2579 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'hugegroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=hugegroup))') 581s pynslcd: DEBUG: connection from pid=2579 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'hugegroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=hugegroup))') 581s pynslcd: DEBUG: connection from pid=2579 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'hugegroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=hugegroup))') 581s pynslcd: DEBUG: connection from pid=2579 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'hugegroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=hugegroup))') 581s pynslcd: DEBUG: connection from pid=2600 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'nstgrp1'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=nstgrp1))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2621 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'nstgrp2'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=nstgrp2))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2642 uid=0 gid=0 581s pynslcd: DEBUG: GroupByNameRequest({'cn': 'nstgrp3'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(cn=nstgrp3))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'testusr2'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testusr2))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByUidRequest({'uidNumber': 1002}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uidNumber=1002))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByMemberRequest({'memberUid': 'testusr2'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testusr2))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(|(memberUid=testusr2)(member=cn=Test User2,ou=people,dc=test,dc=tld)))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=testgroup2,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=nstgrp2,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=largegroup,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=nstgrp1,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=nstgrp3,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6200}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6200))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 6200}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=6200))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test\\2C User4,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=АБВ ГҐДЂЃЕЁ,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=可是当这个U盘在,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='uid=testusr1,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=bar,dc=foo,dc=com', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=testhost,ou=hosts,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 800}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=800))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 800}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=800))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 801}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=801))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 801}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=801))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 802}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=802))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2663 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 802}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=802))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'testusr3'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testusr3))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByUidRequest({'uidNumber': 1003}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uidNumber=1003))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByMemberRequest({'memberUid': 'testusr3'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testusr3))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(|(memberUid=testusr3)(member=cn=Test User3,ou=extra,ou=people,dc=test,dc=tld)))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=nstgrp2,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=largegroup,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=nstgrp3,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1005}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1005))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 801}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=801))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 801}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=801))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 802}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=802))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2672 uid=0 gid=0 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 802}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=802))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp1,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User2,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: LDAPSearch(base='cn=nstgrp2,ou=groups,dc=test,dc=tld', filter='(|(objectClass=posixGroup)(objectClass=groupOfNames))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=Test User3,ou=extra,ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2682 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhost'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhost))') 581s pynslcd: DEBUG: connection from pid=2682 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhost'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhost))') 581s pynslcd: DEBUG: connection from pid=2690 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhostalias'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhostalias))') 581s pynslcd: DEBUG: connection from pid=2690 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhostalias'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhostalias))') 581s pynslcd: DEBUG: connection from pid=2698 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'TESTHOST'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=TESTHOST))') 581s pynslcd: DEBUG: connection from pid=2698 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'TESTHOST'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=TESTHOST))') 581s pynslcd: DEBUG: connection from pid=2706 uid=0 gid=0 581s pynslcd: DEBUG: HostByAddressRequest({'ipHostNumber': '192.0.2.123'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(ipHostNumber=192.0.2.123))') 581s pynslcd: DEBUG: connection from pid=2714 uid=0 gid=0 581s pynslcd: DEBUG: HostAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(objectClass=ipHost)') 581s pynslcd: DEBUG: connection from pid=2724 uid=0 gid=0 581s pynslcd: DEBUG: HostByAddressRequest({'ipHostNumber': '2001:db8::dead:beef'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(ipHostNumber=2001:db8::dead:beef))') 581s pynslcd: DEBUG: connection from pid=2732 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhost2'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhost2))') 581s pynslcd: DEBUG: connection from pid=2732 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhost2'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhost2))') 581s pynslcd: DEBUG: connection from pid=2742 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhost3'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhost3))') 581s pynslcd: DEBUG: connection from pid=2750 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhost3'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhost3))') 581s pynslcd: DEBUG: connection from pid=2750 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhost3'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhost3))') 581s pynslcd: DEBUG: connection from pid=2758 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhost4'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhost4))') 581s pynslcd: DEBUG: connection from pid=2758 uid=0 gid=0 581s pynslcd: DEBUG: HostByNameRequest({'cn': 'testhost4'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipHost)(cn=testhost4))') 581s pynslcd: DEBUG: connection from pid=2769 uid=0 gid=0 581s pynslcd: DEBUG: NetgroupByNameRequest({'cn': 'tstnetgroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=nisNetgroup)(cn=tstnetgroup))') 581s pynslcd: DEBUG: connection from pid=2769 uid=0 gid=0 581s pynslcd: DEBUG: NetgroupByNameRequest({'cn': 'tst2netgroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=nisNetgroup)(cn=tst2netgroup))') 581s pynslcd: DEBUG: connection from pid=2769 uid=0 gid=0 581s pynslcd: DEBUG: NetgroupByNameRequest({'cn': 'tst3netgroup'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=nisNetgroup)(cn=tst3netgroup))') 581s pynslcd: DEBUG: connection from pid=2777 uid=0 gid=0 581s pynslcd: DEBUG: NetgroupByNameRequest({'cn': 'TSTNETGROUP'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=nisNetgroup)(cn=TSTNETGROUP))') 581s pynslcd: DEBUG: cn=tstnetgroup,ou=netgroups,dc=test,dc=tld: cn: does not contain 'TSTNETGROUP' value 581s pynslcd: DEBUG: connection from pid=2786 uid=0 gid=0 581s pynslcd: DEBUG: NetworkByNameRequest({'cn': 'testnet'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipNetwork)(cn=testnet))') 581s pynslcd: DEBUG: connection from pid=2794 uid=0 gid=0 581s pynslcd: DEBUG: NetworkByNameRequest({'cn': 'TESTNET'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipNetwork)(cn=TESTNET))') 581s pynslcd: DEBUG: connection from pid=2802 uid=0 gid=0 581s pynslcd: DEBUG: NetworkByAddressRequest({'ipNetworkNumber': '192.0.2.0'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipNetwork)(ipNetworkNumber=192.0.2.0))') 581s pynslcd: DEBUG: connection from pid=2810 uid=0 gid=0 581s pynslcd: DEBUG: NetworkAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(objectClass=ipNetwork)') 581s pynslcd: DEBUG: connection from pid=2820 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'ecolden'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=ecolden))') 581s pynslcd: DEBUG: connection from pid=2829 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'testusr1'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=testusr1))') 581s pynslcd: DEBUG: connection from pid=2838 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'TESTUSR1'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=TESTUSR1))') 581s pynslcd: DEBUG: uid=testusr1,ou=people,dc=test,dc=tld: uid: does not contain 'TESTUSR1' value 581s pynslcd: DEBUG: connection from pid=2846 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByUidRequest({'uidNumber': 4089}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uidNumber=4089))') 581s pynslcd: DEBUG: connection from pid=2855 uid=0 gid=0 581s pynslcd: DEBUG: PasswdAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=2865 uid=0 gid=0 581s pynslcd: DEBUG: ProtocolByNameRequest({'cn': 'protfoo'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipProtocol)(cn=protfoo))') 581s pynslcd: DEBUG: connection from pid=2873 uid=0 gid=0 581s pynslcd: DEBUG: ProtocolByNameRequest({'cn': 'protfooalias'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipProtocol)(cn=protfooalias))') 581s pynslcd: DEBUG: connection from pid=2881 uid=0 gid=0 581s pynslcd: DEBUG: ProtocolByNameRequest({'cn': 'PROTFOO'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipProtocol)(cn=PROTFOO))') 581s pynslcd: DEBUG: cn=protfoo,ou=protocols,dc=test,dc=tld: cn: does not contain 'PROTFOO' value 581s pynslcd: DEBUG: connection from pid=2889 uid=0 gid=0 581s pynslcd: DEBUG: ProtocolByNameRequest({'cn': 'PROTFOOALIAS'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipProtocol)(cn=PROTFOOALIAS))') 581s pynslcd: DEBUG: cn=protfoo,ou=protocols,dc=test,dc=tld: cn: does not contain 'PROTFOOALIAS' value 581s pynslcd: DEBUG: connection from pid=2897 uid=0 gid=0 581s pynslcd: DEBUG: ProtocolByNumberRequest({'ipProtocolNumber': 253}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipProtocol)(ipProtocolNumber=253))') 581s pynslcd: DEBUG: connection from pid=2913 uid=0 gid=0 581s pynslcd: DEBUG: ProtocolAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(objectClass=ipProtocol)') 581s pynslcd: DEBUG: connection from pid=2923 uid=0 gid=0 581s pynslcd: DEBUG: RpcByNameRequest({'cn': 'rpcfoo'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=oncRpc)(cn=rpcfoo))') 581s pynslcd: DEBUG: connection from pid=2931 uid=0 gid=0 581s pynslcd: DEBUG: RpcByNameRequest({'cn': 'rpcfooalias'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=oncRpc)(cn=rpcfooalias))') 581s pynslcd: DEBUG: connection from pid=2939 uid=0 gid=0 581s pynslcd: DEBUG: RpcByNameRequest({'cn': 'RPCFOO'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=oncRpc)(cn=RPCFOO))') 581s pynslcd: DEBUG: cn=rpcfoo,ou=rpcs,dc=test,dc=tld: cn: does not contain 'RPCFOO' value 581s pynslcd: DEBUG: connection from pid=2947 uid=0 gid=0 581s pynslcd: DEBUG: RpcByNumberRequest({'oncRpcNumber': 160002}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=oncRpc)(oncRpcNumber=160002))') 581s pynslcd: DEBUG: connection from pid=2955 uid=0 gid=0 581s pynslcd: DEBUG: RpcAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(objectClass=oncRpc)') 581s pynslcd: DEBUG: connection from pid=2965 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'foosrv'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=foosrv))') 581s pynslcd: DEBUG: connection from pid=2973 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'foosrv', 'ipServiceProtocol': 'tcp'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=foosrv)(ipServiceProtocol=tcp))') 581s pynslcd: DEBUG: connection from pid=2981 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'foosrv', 'ipServiceProtocol': 'udp'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=foosrv)(ipServiceProtocol=udp))') 581s pynslcd: DEBUG: connection from pid=2989 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'FOOSRV'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=FOOSRV))') 581s pynslcd: DEBUG: cn=foosrv,ou=services,dc=test,dc=tld: cn: does not contain 'FOOSRV' value 581s pynslcd: DEBUG: connection from pid=2997 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'foosrv', 'ipServiceProtocol': 'tCp'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=foosrv)(ipServiceProtocol=tCp))') 581s pynslcd: DEBUG: cn=foosrv,ou=services,dc=test,dc=tld: ipServiceProtocol: does not contain 'tCp' value 581s pynslcd: DEBUG: connection from pid=3005 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNumberRequest({'ipServicePort': 15349, 'ipServiceProtocol': 'tcp'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(ipServicePort=15349)(ipServiceProtocol=tcp))') 581s pynslcd: DEBUG: connection from pid=3013 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNumberRequest({'ipServicePort': 15349, 'ipServiceProtocol': 'udp'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(ipServicePort=15349)(ipServiceProtocol=udp))') 581s pynslcd: DEBUG: connection from pid=3021 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'barsrv'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=barsrv))') 581s pynslcd: DEBUG: connection from pid=3029 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'barsrv', 'ipServiceProtocol': 'tcp'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=barsrv)(ipServiceProtocol=tcp))') 581s pynslcd: DEBUG: connection from pid=3037 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'barsrv', 'ipServiceProtocol': 'udp'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=barsrv)(ipServiceProtocol=udp))') 581s pynslcd: DEBUG: connection from pid=3045 uid=0 gid=0 581s pynslcd: DEBUG: ServiceAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(objectClass=ipService)') 581s pynslcd: DEBUG: connection from pid=3055 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'sssin'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=sssin))') 581s pynslcd: DEBUG: connection from pid=3063 uid=0 gid=0 581s pynslcd: DEBUG: ServiceByNameRequest({'cn': 'SSSIN'}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(&(objectClass=ipService)(cn=SSSIN))') 581s pynslcd: DEBUG: connection from pid=3074 uid=0 gid=0 581s pynslcd: DEBUG: ServiceAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='dc=test,dc=tld', filter='(objectClass=ipService)') 581s pynslcd: DEBUG: connection from pid=3084 uid=0 gid=0 581s pynslcd: DEBUG: ShadowByNameRequest({'uid': 'ecordas'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=shadowAccount)(uid=ecordas))') 581s pynslcd: DEBUG: connection from pid=3094 uid=0 gid=0 581s pynslcd: DEBUG: ShadowByNameRequest({'uid': 'adishaw'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=shadowAccount)(uid=adishaw))') 581s pynslcd: DEBUG: connection from pid=3104 uid=0 gid=0 581s pynslcd: DEBUG: ShadowByNameRequest({'uid': 'ADISHAW'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=shadowAccount)(uid=ADISHAW))') 581s pynslcd: DEBUG: cn=Alison Dishaw,ou=lotsofpeople,dc=test,dc=tld: uid: does not contain 'ADISHAW' value 581s pynslcd: DEBUG: connection from pid=3108 uid=0 gid=0 581s pynslcd: DEBUG: PasswdAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=3115 uid=0 gid=0 581s pynslcd: DEBUG: ShadowAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(objectClass=shadowAccount)') 581s pynslcd: DEBUG: connection from pid=3119 uid=0 gid=0 581s pynslcd: DEBUG: PasswdAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(objectClass=posixAccount)') 581s pynslcd: DEBUG: connection from pid=3128 uid=0 gid=0 581s pynslcd: DEBUG: ShadowAllRequest({}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(objectClass=shadowAccount)') 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: ConfigGetRequest({'cfgopt': 1}) 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: PAMAuthenticationRequest({'username': '', 'service': 'passwd', 'ruser': '', 'rhost': '', 'tty': '', 'password': ''}) 581s pynslcd: DEBUG: LDAPSearch(base='cn=admin,dc=test,dc=tld', filter='(objectClass=*)') 581s pynslcd: DEBUG: bind successful 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: ConfigGetRequest({'cfgopt': 1}) 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3187 uid=0 gid=0 581s pynslcd: DEBUG: PAMPasswordModificationRequest({'username': 'vsefcovic', 'service': 'passwd', 'ruser': '', 'rhost': '', 'tty': '', 'asroot': 1, 'oldpassword': '', 'newpassword': '***'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: LDAPSearch(base='cn=admin,dc=test,dc=tld', filter='(objectClass=*)') 581s pynslcd: DEBUG: pwmod successful 581s pynslcd: DEBUG: connection from pid=3190 uid=0 gid=0 581s pynslcd: DEBUG: PAMAuthorisationRequest({'username': 'nobody', 'service': 'su-l', 'ruser': 'root', 'rhost': '', 'tty': '/dev/pts/1'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=nobody))') 581s pynslcd: exception in worker 581s Traceback (most recent call last): 581s File "/usr/sbin/pynslcd", line 260, in worker 581s acceptconnection(nslcd_serversocket, session) 581s ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/sbin/pynslcd", line 231, in acceptconnection 581s handler(fp, session, uid)() 581s ~~~~~~~~~~~~~~~~~~~~~~~~~^^ 581s File "/usr/share/pynslcd/common.py", line 121, in __call__ 581s self.handle_request(parameters) 581s ~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^ 581s File "/usr/share/pynslcd/pam.py", line 258, in handle_request 581s self.validate(parameters) 581s ~~~~~~~~~~~~~^^^^^^^^^^^^ 581s File "/usr/share/pynslcd/pam.py", line 138, in validate 581s raise ValueError('%r: user not found' % parameters['username']) 581s ValueError: 'nobody': user not found 581s pynslcd: DEBUG: connection from pid=3190 uid=0 gid=0 581s pynslcd: DEBUG: GroupByMemberRequest({'memberUid': 'nobody'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=nobody))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(memberUid=nobody))') 581s pynslcd: DEBUG: connection from pid=3190 uid=0 gid=0 581s pynslcd: DEBUG: PAMSessionOpenRequest({'username': 'nobody', 'service': 'su-l', 'ruser': 'root', 'rhost': '', 'tty': '/dev/pts/1'}) 581s pynslcd: DEBUG: connection from pid=3205 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3205 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3205 uid=0 gid=65534 581s pynslcd: DEBUG: ShadowByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=shadowAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3205 uid=0 gid=65534 581s pynslcd: exception in worker 581s Traceback (most recent call last): 581s File "/usr/sbin/pynslcd", line 260, in worker 581s acceptconnection(nslcd_serversocket, session) 581s ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/sbin/pynslcd", line 231, in acceptconnection 581s handler(fp, session, uid)() 581s ~~~~~~~~~~~~~~~~~~~~~~~~~^^ 581s File "/usr/share/pynslcd/common.py", line 117, in __call__ 581s parameters = self.read_parameters(self.fp) or {} 581s ~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 581s File "/usr/share/pynslcd/passwd.py", line 113, in read_parameters 581s common.validate_name(name) 581s ~~~~~~~~~~~~~~~~~~~~^^^^^^ 581s File "/usr/share/pynslcd/common.py", line 54, in validate_name 581s raise ValueError('%r: denied by validnames option' % name) 581s ValueError: 'pam_unix_non_existent:': denied by validnames option 581s pynslcd: DEBUG: connection from pid=3205 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3205 uid=0 gid=65534 581s pynslcd: DEBUG: ShadowByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=shadowAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3205 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3205 uid=0 gid=65534 581s pynslcd: DEBUG: PAMAuthenticationRequest({'username': 'vsefcovic', 'service': 'su-l', 'ruser': 'nobody', 'rhost': '', 'tty': '/dev/pts/1', 'password': '***'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: bind failed: {'msgtype': 97, 'msgid': 1, 'result': 49, 'desc': 'Invalid credentials', 'ctrls': [('1.3.6.1.4.1.42.2.27.8.5.1', 0, b'0\x00')]} 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: ShadowByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=shadowAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: exception in worker 581s Traceback (most recent call last): 581s File "/usr/sbin/pynslcd", line 260, in worker 581s acceptconnection(nslcd_serversocket, session) 581s ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/sbin/pynslcd", line 231, in acceptconnection 581s handler(fp, session, uid)() 581s ~~~~~~~~~~~~~~~~~~~~~~~~~^^ 581s File "/usr/share/pynslcd/common.py", line 117, in __call__ 581s parameters = self.read_parameters(self.fp) or {} 581s ~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 581s File "/usr/share/pynslcd/passwd.py", line 113, in read_parameters 581s common.validate_name(name) 581s ~~~~~~~~~~~~~~~~~~~~^^^^^^ 581s File "/usr/share/pynslcd/common.py", line 54, in validate_name 581s raise ValueError('%r: denied by validnames option' % name) 581s ValueError: 'pam_unix_non_existent:': denied by validnames option 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: ShadowByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=shadowAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PAMAuthenticationRequest({'username': 'vsefcovic', 'service': 'su-l', 'ruser': 'nobody', 'rhost': '', 'tty': '/dev/pts/1', 'password': '***'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: PasswordPolicyControl found: error=None (None), timeBeforeExpiration=None, graceAuthNsRemaining=None 581s pynslcd: DEBUG: LDAPSearch(base='cn=Veronica Sefcovic+uid=vsefcovic,ou=lotsofpeople,dc=test,dc=tld', filter='(objectClass=*)') 581s pynslcd: DEBUG: bind successful 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByMemberRequest({'memberUid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(|(memberUid=vsefcovic)(member=cn=Veronica Sefcovic+uid=vsefcovic,ou=lotsofpeople,dc=test,dc=tld)))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=hugegroup,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: ShadowByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=shadowAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PAMAuthorisationRequest({'username': 'vsefcovic', 'service': 'su-l', 'ruser': 'nobody', 'rhost': '', 'tty': '/dev/pts/1'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByMemberRequest({'memberUid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(|(memberUid=vsefcovic)(member=cn=Veronica Sefcovic+uid=vsefcovic,ou=lotsofpeople,dc=test,dc=tld)))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=hugegroup,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByMemberRequest({'memberUid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(|(memberUid=vsefcovic)(member=cn=Veronica Sefcovic+uid=vsefcovic,ou=lotsofpeople,dc=test,dc=tld)))') 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(member=cn=hugegroup,ou=groups,dc=test,dc=tld))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PAMSessionOpenRequest({'username': 'vsefcovic', 'service': 'su-l', 'ruser': 'nobody', 'rhost': '', 'tty': '/dev/pts/1'}) 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: ShadowByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=shadowAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: PasswdByUidRequest({'uidNumber': 5973}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uidNumber=5973))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3222 uid=5973 gid=1000 581s pynslcd: DEBUG: GroupByGidRequest({'gidNumber': 1006}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=groups,dc=test,dc=tld', filter='(&(|(objectClass=posixGroup)(objectClass=groupOfNames))(gidNumber=1006))') 581s pynslcd: DEBUG: connection from pid=3223 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByUidRequest({'uidNumber': 5973}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uidNumber=5973))') 581s pynslcd: DEBUG: connection from pid=3223 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3223 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3223 uid=0 gid=1000 581s pynslcd: DEBUG: ConfigGetRequest({'cfgopt': 1}) 581s pynslcd: DEBUG: connection from pid=3223 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3223 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3223 uid=0 gid=1000 581s pynslcd: DEBUG: PAMAuthenticationRequest({'username': 'vsefcovic', 'service': 'passwd', 'ruser': '', 'rhost': '', 'tty': '', 'password': '***'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: bind failed: {'msgtype': 97, 'msgid': 1, 'result': 49, 'desc': 'Invalid credentials', 'ctrls': [('1.3.6.1.4.1.42.2.27.8.5.1', 0, b'0\x00')]} 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByUidRequest({'uidNumber': 5973}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uidNumber=5973))') 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: ConfigGetRequest({'cfgopt': 1}) 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: PAMAuthenticationRequest({'username': 'vsefcovic', 'service': 'passwd', 'ruser': '', 'rhost': '', 'tty': '', 'password': '***'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: PasswordPolicyControl found: error=None (None), timeBeforeExpiration=None, graceAuthNsRemaining=None 581s pynslcd: DEBUG: LDAPSearch(base='cn=Veronica Sefcovic+uid=vsefcovic,ou=lotsofpeople,dc=test,dc=tld', filter='(objectClass=*)') 581s pynslcd: DEBUG: bind successful 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: ConfigGetRequest({'cfgopt': 1}) 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3224 uid=0 gid=1000 581s pynslcd: DEBUG: PAMPasswordModificationRequest({'username': 'vsefcovic', 'service': 'passwd', 'ruser': '', 'rhost': '', 'tty': '', 'asroot': 0, 'oldpassword': '***', 'newpassword': '***'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: PasswordPolicyControl found: error=None (None), timeBeforeExpiration=None, graceAuthNsRemaining=None 581s pynslcd: DEBUG: LDAPSearch(base='cn=Veronica Sefcovic+uid=vsefcovic,ou=lotsofpeople,dc=test,dc=tld', filter='(objectClass=*)') 581s pynslcd: exception in worker 581s Traceback (most recent call last): 581s File "/usr/share/pynslcd/pam.py", line 96, in pwmod 581s conn.passwd_s(userdn, None, newpassword) 581s ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 915, in passwd_s 581s return self._apply_method_s(SimpleLDAPObject.passwd_s,*args,**kwargs) 581s ~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 972, in _apply_method_s 581s return func(self,*args,**kwargs) 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 453, in passwd_s 581s respoid, respvalue = self.extop_result(msgid, all=1, timeout=self.timeout) 581s ~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 378, in extop_result 581s resulttype,msg,msgid,respctrls,respoid,respvalue = self.result4(msgid,all=1,timeout=self.timeout,add_ctrls=1,add_intermediates=1,add_extop=1) 581s ~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 553, in result4 581s ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop) 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 128, in _ldap_call 581s result = func(*args,**kwargs) 581s ldap.CONSTRAINT_VIOLATION: {'msgtype': 120, 'msgid': 3, 'result': 19, 'desc': 'Constraint violation', 'ctrls': [], 'info': 'Password is too young to change'} 581s 581s During handling of the above exception, another exception occurred: 581s 581s Traceback (most recent call last): 581s File "/usr/sbin/pynslcd", line 260, in worker 581s acceptconnection(nslcd_serversocket, session) 581s ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/sbin/pynslcd", line 231, in acceptconnection 581s handler(fp, session, uid)() 581s ~~~~~~~~~~~~~~~~~~~~~~~~~^^ 581s File "/usr/share/pynslcd/common.py", line 121, in __call__ 581s self.handle_request(parameters) 581s ~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^ 581s File "/usr/share/pynslcd/pam.py", line 316, in handle_request 581s pwmod(conn, parameters['userdn'], parameters['oldpassword'], parameters['newpassword']) 581s ~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/share/pynslcd/pam.py", line 100, in pwmod 581s conn.passwd_s(userdn, oldpassword, newpassword) 581s ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 915, in passwd_s 581s return self._apply_method_s(SimpleLDAPObject.passwd_s,*args,**kwargs) 581s ~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 972, in _apply_method_s 581s return func(self,*args,**kwargs) 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 453, in passwd_s 581s respoid, respvalue = self.extop_result(msgid, all=1, timeout=self.timeout) 581s ~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 378, in extop_result 581s resulttype,msg,msgid,respctrls,respoid,respvalue = self.result4(msgid,all=1,timeout=self.timeout,add_ctrls=1,add_intermediates=1,add_extop=1) 581s ~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 553, in result4 581s ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop) 581s File "/usr/lib/python3/dist-packages/ldap/ldapobject.py", line 128, in _ldap_call 581s result = func(*args,**kwargs) 581s ldap.CONSTRAINT_VIOLATION: {'msgtype': 120, 'msgid': 4, 'result': 19, 'desc': 'Constraint violation', 'ctrls': [], 'info': 'Password is too young to change'} 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PasswdByNameRequest({'uid': 'vsefcovic'}) 581s pynslcd: DEBUG: LDAPSearch(base='ou=people,dc=test,dc=tld', filter='(&(objectClass=posixAccount)(uid=vsefcovic))') 581s pynslcd: DEBUG: connection from pid=3208 uid=0 gid=65534 581s pynslcd: DEBUG: PAMSessionCloseRequest({'username': 'vsefcovic', 'service': 'su-l', 'ruser': 'nobody', 'rhost': '', 'tty': '/dev/pts/1', 'session_id': 'VDS6Fns35WLjiwPUFbNiUETU8'}) 581s testsuite: cleaning up... 581s Failed to stop nslcd.service: Unit nslcd.service not loaded. 581s Stopping OpenLDAP: slapd done. 581s Cleaning /tmp/slapd.2FsnFw... done. 581s testsuite: restoring configuration... 581s autopkgtest [16:06:55]: test testsuite: -----------------------] 582s testsuite FLAKY non-zero exit status 1 582s autopkgtest [16:06:56]: test testsuite: - - - - - - - - - - results - - - - - - - - - - 583s autopkgtest [16:06:57]: @@@@@@@@@@@@@@@@@@@@ summary 583s testsuite PASS 583s testsuite FLAKY non-zero exit status 1 589s nova [W] Using flock in prodstack6-s390x 589s flock: timeout while waiting to get lock 589s Creating nova instance adt-plucky-s390x-nss-pam-ldapd-20250315-155714-juju-7f2275-prod-proposed-migration-environment-15-f174977a-51ae-4078-9a55-ba4b2f3f81b1 from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)... 589s nova [W] Timed out waiting for 5003a74a-9a3e-4494-bd78-35850efeee24 to get deleted. 589s nova [W] Using flock in prodstack6-s390x 589s Creating nova instance adt-plucky-s390x-nss-pam-ldapd-20250315-155714-juju-7f2275-prod-proposed-migration-environment-15-f174977a-51ae-4078-9a55-ba4b2f3f81b1 from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)... 589s nova [W] Timed out waiting for 9a649ef2-9d5a-48c3-8c03-f2eaf5306b1e to get deleted.