Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

HTTP Bad Request on login with ipa modules #43091

Closed
3coma3 opened this issue Jul 20, 2018 · 12 comments
Closed

HTTP Bad Request on login with ipa modules #43091

3coma3 opened this issue Jul 20, 2018 · 12 comments
Labels
affects_2.6 This issue/PR affects Ansible v2.6 bug This issue/PR relates to a bug. ipa IPA community module This issue/PR relates to a module. support:community This issue/PR relates to code supported by the Ansible community. support:core This issue/PR relates to code supported by the Ansible Engineering Team.

Comments

@3coma3
Copy link

3coma3 commented Jul 20, 2018

SUMMARY

I'm getting the following error when using ipa_*:
fatal: [vm1.ipatest001.net]: FAILED! => {"changed": false, "msg": "login: HTTP Error 400: Bad Request"}

Verified with curl that the FreeIPA password endpoint authenticates correctly and returns the session data.

ISSUE TYPE
  • Bug Report
COMPONENT NAME

ipa_group
ipa_user
module_utils/ipa
(probably the other ipa user modules are affected, but haven't tested)

ANSIBLE VERSION
ansible 2.6.1
  config file = /home/testcase/ansible.cfg
  configured module search path = [u'/home/testcase/.ansible/plugins/modules', u'/usr/share/ansible/plugins/modules']
  ansible python module location = /usr/lib/python2.7/dist-packages/ansible
  executable location = /usr/bin/ansible
  python version = 2.7.13 (default, Nov 24 2017, 17:33:09) [GCC 6.3.0 20170516]

CONFIGURATION

DEFAULT_ACTION_PLUGIN_PATH(/home/testcase/ansible.cfg) = [u'/home/testcase/external/ansible-freeipa/action_plugins']
DEFAULT_HOST_LIST(/home/testcase/ansible.cfg) = [u'/home/testcase/hosts.yml']
DEFAULT_ROLES_PATH(/home/testcase/ansible.cfg) = [u'/home/testcase/roles', u'/hometestcase/external/ansible-freeipa/roles']
DEFAULT_VAULT_PASSWORD_FILE(/home/testcase/ansible.cfg) = /home/testcase/.vaultpass
HOST_KEY_CHECKING(/home/testcase/ansible.cfg) = True

OS / ENVIRONMENT

Ansible host: Debian Stretch
Managed host: Centos 7.6

STEPS TO REPRODUCE
    - name: "Create a test freeipa group"
      ipa_group:
        state: present
        cn: "testgroup"
        description: "Test group"
        ipa_host: "{{ ansible_hostname }}"
        ipa_pass: "{{ ipaadmin_password }}"
        validate_certs: no
      register: fixture_group_dict

ansible-playbook playbooks/testgroup.yml

EXPECTED RESULTS

The group would be created

ACTUAL RESULTS

The login fails with the following error:
fatal: [vm1.ipasrvtest001.net]: FAILED! => {"changed": false, "msg": "login: HTTP Error 400: Bad Request"}
to retry, use: --limit @/home/testcase/playbooks/fixture.retry

ansible-playbook 2.6.1
  config file = /home/testcase/ansible.cfg
  configured module search path = [u'/home/testcase/.ansible/plugins/modules', u'/usr/share/ansible/plugins/modules']
  ansible python module location = /usr/lib/python2.7/dist-packages/ansible
  executable location = /usr/bin/ansible-playbook
  python version = 2.7.13 (default, Nov 24 2017, 17:33:09) [GCC 6.3.0 20170516]
Using /home/testcase/ansible.cfg as config file
setting up inventory plugins
Parsed /home/testcase/hosts.yml inventory source with yaml plugin
Loading callback plugin default of type stdout, v2.0 from /usr/lib/python2.7/dist-packages/ansible/plugins/callback/default.pyc

PLAYBOOK: testgroup.yml ****************************************************************************************************************
1 plays in playbooks/testgroup.yml

PLAY [Play: create a test freeipa group] *********************************************************************************************
Trying secret FileVaultSecret(filename='/home/testcase/.vaultpass') for vault_id=default
Trying secret FileVaultSecret(filename='/home/testcase/.vaultpass') for vault_id=default

TASK [Gathering Facts] ***************************************************************************************************************
task path: /home/testcase/playbooks/testgroup.yml:2
Trying secret FileVaultSecret(filename='/home/testcase/.vaultpass') for vault_id=default
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 vm1.ipasrvtest001.net '/bin/sh -c '"'"'echo ~2mrrz18sfugo-ansible && sleep 0'"'"''
<vm1.ipasrvtest001.net> (0, '/home/2mrrz18sfugo-ansible\n', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug1: Control socket "/home/testcase/.ansible/cp/64f97777f2" does not exist\r\ndebug2: resolving "vm1.ipasrvtest001.net" port 22\r\ndebug2: ssh_connect_direct: needpriv 0\r\ndebug1: Connecting to vm1.ipasrvtest001.net [192.168.100.138] port 22.\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug1: fd 3 clearing O_NONBLOCK\r\ndebug1: Connection established.\r\ndebug3: timeout: 10000 ms remain after connect\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /home/testcase/.ssh/id_rsa type -1\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /home/testcase/.ssh/id_rsa-cert type -1\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /home/testcase/.ssh/id_dsa type -1\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /home/testcase/.ssh/id_dsa-cert type -1\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /home/testcase/.ssh/id_ecdsa type -1\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /home/testcase/.ssh/id_ecdsa-cert type -1\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /home/testcase/.ssh/id_ed25519 type -1\r\ndebug1: key_load_public: No such file or directory\r\ndebug1: identity file /home/testcase/.ssh/id_ed25519-cert type -1\r\ndebug1: Enabling compatibility mode for protocol 2.0\r\ndebug1: Local version string SSH-2.0-OpenSSH_7.4p1 Debian-10+deb9u3\r\ndebug1: Remote protocol version 2.0, remote software version OpenSSH_7.4\r\ndebug1: match: OpenSSH_7.4 pat OpenSSH* compat 0x04000000\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug1: Authenticating to vm1.ipasrvtest001.net:22 as \'2mrrz18sfugo-ansible\'\r\ndebug3: hostkeys_foreach: reading file "/home/testcase/.ssh/known_hosts"\r\ndebug3: record_hostkey: found key type ECDSA in file /home/testcase/.ssh/known_hosts:13\r\ndebug3: load_hostkeys: loaded 1 keys from vm1.ipasrvtest001.net\r\ndebug3: order_hostkeyalgs: prefer hostkeyalgs: ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521\r\ndebug3: send packet: type 20\r\ndebug1: SSH2_MSG_KEXINIT sent\r\ndebug3: receive packet: type 20\r\ndebug1: SSH2_MSG_KEXINIT received\r\ndebug2: local client KEXINIT proposal\r\ndebug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,ext-info-c\r\ndebug2: host key algorithms: ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-ed25519-cert-v01@openssh.com,ssh-rsa-cert-v01@openssh.com,ssh-ed25519,rsa-sha2-512,rsa-sha2-256,ssh-rsa\r\ndebug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc\r\ndebug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc\r\ndebug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1\r\ndebug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1\r\ndebug2: compression ctos: zlib@openssh.com,zlib,none\r\ndebug2: compression stoc: zlib@openssh.com,zlib,none\r\ndebug2: languages ctos: \r\ndebug2: languages stoc: \r\ndebug2: first_kex_follows 0 \r\ndebug2: reserved 0 \r\ndebug2: peer server KEXINIT proposal\r\ndebug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1\r\ndebug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519\r\ndebug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc,blowfish-cbc,cast128-cbc,3des-cbc\r\ndebug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc,blowfish-cbc,cast128-cbc,3des-cbc\r\ndebug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1\r\ndebug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1\r\ndebug2: compression ctos: none,zlib@openssh.com\r\ndebug2: compression stoc: none,zlib@openssh.com\r\ndebug2: languages ctos: \r\ndebug2: languages stoc: \r\ndebug2: first_kex_follows 0 \r\ndebug2: reserved 0 \r\ndebug1: kex: algorithm: curve25519-sha256\r\ndebug1: kex: host key algorithm: ecdsa-sha2-nistp256\r\ndebug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com\r\ndebug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com\r\ndebug3: send packet: type 30\r\ndebug1: expecting SSH2_MSG_KEX_ECDH_REPLY\r\ndebug3: receive packet: type 31\r\ndebug1: Server host key: ecdsa-sha2-nistp256 SHA256:R/CI1NsZ/ed93pEEfoSDXIqH4rbkad9Tjnq/a9Vy6OQ\r\ndebug3: hostkeys_foreach: reading file "/home/testcase/.ssh/known_hosts"\r\ndebug3: record_hostkey: found key type ECDSA in file /home/testcase/.ssh/known_hosts:13\r\ndebug3: load_hostkeys: loaded 1 keys from vm1.ipasrvtest001.net\r\ndebug3: hostkeys_foreach: reading file "/home/testcase/.ssh/known_hosts"\r\ndebug3: record_hostkey: found key type ECDSA in file /home/testcase/.ssh/known_hosts:10\r\ndebug3: load_hostkeys: loaded 1 keys from 192.168.100.138\r\ndebug1: Host \'vm1.ipasrvtest001.net\' is known and matches the ECDSA host key.\r\ndebug1: Found key in /home/testcase/.ssh/known_hosts:13\r\ndebug3: send packet: type 21\r\ndebug2: set_newkeys: mode 1\r\ndebug1: rekey after 134217728 blocks\r\ndebug1: SSH2_MSG_NEWKEYS sent\r\ndebug1: expecting SSH2_MSG_NEWKEYS\r\ndebug3: receive packet: type 21\r\ndebug1: SSH2_MSG_NEWKEYS received\r\ndebug2: set_newkeys: mode 0\r\ndebug1: rekey after 134217728 blocks\r\ndebug2: key: /home/testcase/.ssh/local (0x55f5790eb440), agent\r\ndebug2: key: testcase@js-lap (0x55f5790eaba0), agent\r\ndebug2: key: /home/testcase/.ssh/id_rsa ((nil))\r\ndebug2: key: /home/testcase/.ssh/id_dsa ((nil))\r\ndebug2: key: /home/testcase/.ssh/id_ecdsa ((nil))\r\ndebug2: key: /home/testcase/.ssh/id_ed25519 ((nil))\r\ndebug3: send packet: type 5\r\ndebug3: receive packet: type 7\r\ndebug1: SSH2_MSG_EXT_INFO received\r\ndebug1: kex_input_ext_info: server-sig-algs=<rsa-sha2-256,rsa-sha2-512>\r\ndebug3: receive packet: type 6\r\ndebug2: service_accept: ssh-userauth\r\ndebug1: SSH2_MSG_SERVICE_ACCEPT received\r\ndebug3: send packet: type 50\r\ndebug3: receive packet: type 51\r\ndebug1: Authentications that can continue: publickey,gssapi-keyex,gssapi-with-mic,password\r\ndebug3: start over, passed a different list publickey,gssapi-keyex,gssapi-with-mic,password\r\ndebug3: preferred gssapi-with-mic,gssapi-keyex,hostbased,publickey\r\ndebug3: authmethod_lookup gssapi-with-mic\r\ndebug3: remaining preferred: gssapi-keyex,hostbased,publickey\r\ndebug3: authmethod_is_enabled gssapi-with-mic\r\ndebug1: Next authentication method: gssapi-with-mic\r\ndebug1: Unspecified GSS failure.  Minor code may provide more information\nNo Kerberos credentials available (default cache: FILE:/tmp/krb5cc_1100)\n\r\ndebug1: Unspecified GSS failure.  Minor code may provide more information\nNo Kerberos credentials available (default cache: FILE:/tmp/krb5cc_1100)\n\r\ndebug2: we did not send a packet, disable method\r\ndebug3: authmethod_lookup gssapi-keyex\r\ndebug3: remaining preferred: hostbased,publickey\r\ndebug3: authmethod_is_enabled gssapi-keyex\r\ndebug1: Next authentication method: gssapi-keyex\r\ndebug1: No valid Key exchange context\r\ndebug2: we did not send a packet, disable method\r\ndebug3: authmethod_lookup publickey\r\ndebug3: remaining preferred: ,publickey\r\ndebug3: authmethod_is_enabled publickey\r\ndebug1: Next authentication method: publickey\r\ndebug1: Offering RSA public key: /home/testcase/.ssh/local\r\ndebug3: send_pubkey_test\r\ndebug3: send packet: type 50\r\ndebug2: we sent a publickey packet, wait for reply\r\ndebug3: receive packet: type 51\r\ndebug1: Authentications that can continue: publickey,gssapi-keyex,gssapi-with-mic,password\r\ndebug1: Offering RSA public key: testcase@js-lap\r\ndebug3: send_pubkey_test\r\ndebug3: send packet: type 50\r\ndebug2: we sent a publickey packet, wait for reply\r\ndebug3: receive packet: type 60\r\ndebug1: Server accepts key: pkalg rsa-sha2-512 blen 535\r\ndebug2: input_userauth_pk_ok: fp SHA256:KGSz2gZpT8HFzcU8BAhbY5yOQIJmZMjvPzzK57tG50A\r\ndebug3: sign_and_send_pubkey: RSA SHA256:KGSz2gZpT8HFzcU8BAhbY5yOQIJmZMjvPzzK57tG50A\r\ndebug3: send packet: type 50\r\ndebug3: receive packet: type 52\r\ndebug1: Enabling compression at level 6.\r\ndebug1: Authentication succeeded (publickey).\r\nAuthenticated to vm1.ipasrvtest001.net ([192.168.100.138]:22).\r\ndebug1: setting up multiplex master socket\r\ndebug3: muxserver_listen: temporary control path /home/testcase/.ansible/cp/64f97777f2.U6HpotuEnlIdyENw\r\ndebug2: fd 5 setting O_NONBLOCK\r\ndebug3: fd 5 is O_NONBLOCK\r\ndebug3: fd 5 is O_NONBLOCK\r\ndebug1: channel 0: new [/home/testcase/.ansible/cp/64f97777f2]\r\ndebug3: muxserver_listen: mux listener channel 0 fd 5\r\ndebug2: fd 3 setting TCP_NODELAY\r\ndebug3: ssh_packet_set_tos: set IP_TOS 0x08\r\ndebug1: control_persist_detach: backgrounding master process\r\ndebug2: control_persist_detach: background process is 31809\r\ndebug2: fd 5 setting O_NONBLOCK\r\ndebug1: forking to background\r\ndebug1: Entering interactive session.\r\ndebug1: pledge: id\r\ndebug2: set_control_persist_exit_time: schedule exit in 60 seconds\r\ndebug1: multiplexing control connection\r\ndebug2: fd 6 setting O_NONBLOCK\r\ndebug3: fd 6 is O_NONBLOCK\r\ndebug1: channel 1: new [mux-control]\r\ndebug3: channel_post_mux_listener: new mux channel 1 fd 6\r\ndebug3: mux_master_read_cb: channel 1: hello sent\r\ndebug2: set_control_persist_exit_time: cancel scheduled exit\r\ndebug3: mux_master_read_cb: channel 1 packet type 0x00000001 len 4\r\ndebug2: process_mux_master_hello: channel 1 slave version 4\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_master_read_cb: channel 1 packet type 0x10000004 len 4\r\ndebug2: process_mux_alive_check: channel 1: alive check\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug3: mux_master_read_cb: channel 1 packet type 0x10000002 len 337\r\ndebug2: process_mux_new_session: channel 1: request tty 0, X 0, agent 0, subsys 0, term "xterm-256color", cmd "/bin/sh -c \'echo ~2mrrz18sfugo-ansible && sleep 0\'", env 9\r\ndebug3: process_mux_new_session: got fds stdin 7, stdout 8, stderr 9\r\ndebug2: fd 8 setting O_NONBLOCK\r\ndebug2: fd 9 setting O_NONBLOCK\r\ndebug1: channel 2: new [client-session]\r\ndebug2: process_mux_new_session: channel_new: 2 linked to control channel 1\r\ndebug2: channel 2: send open\r\ndebug3: send packet: type 90\r\ndebug3: receive packet: type 80\r\ndebug1: client_input_global_request: rtype hostkeys-00@openssh.com want_reply 0\r\ndebug3: receive packet: type 91\r\ndebug2: callback start\r\ndebug2: client_session2_setup: id 2\r\ndebug1: Sending environment.\r\ndebug1: Sending env LC_MEASUREMENT = es_AR.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending env LC_PAPER = es_AR.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending env LC_MONETARY = es_AR.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending env LANG = en_US.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending env LC_NAME = es_AR.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending env LC_ADDRESS = es_AR.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending env LC_NUMERIC = es_AR.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending env LC_TELEPHONE = es_AR.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending env LC_IDENTIFICATION = es_AR.UTF-8\r\ndebug2: channel 2: request env confirm 0\r\ndebug3: send packet: type 98\r\ndebug1: Sending command: /bin/sh -c \'echo ~2mrrz18sfugo-ansible && sleep 0\'\r\ndebug2: channel 2: request exec confirm 1\r\ndebug3: send packet: type 98\r\ndebug3: mux_session_confirm: sending success reply\r\ndebug2: callback done\r\ndebug2: channel 2: open confirm rwindow 0 rmax 32768\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug2: channel 2: rcvd adjust 2097152\r\ndebug3: receive packet: type 99\r\ndebug2: channel_input_status_confirm: type 99 id 2\r\ndebug2: exec request accepted on channel 2\r\ndebug3: receive packet: type 98\r\ndebug1: client_input_channel_req: channel 2 rtype exit-status reply 0\r\ndebug3: mux_exit_message: channel 2: exit message, exitval 0\r\ndebug3: receive packet: type 98\r\ndebug1: client_input_channel_req: channel 2 rtype eow@openssh.com reply 0\r\ndebug2: channel 2: rcvd eow\r\ndebug2: channel 2: close_read\r\ndebug2: channel 2: input open -> closed\r\ndebug3: receive packet: type 96\r\ndebug2: channel 2: rcvd eof\r\ndebug2: channel 2: output open -> drain\r\ndebug2: channel 2: obuf empty\r\ndebug2: channel 2: close_write\r\ndebug2: channel 2: output drain -> closed\r\ndebug3: receive packet: type 97\r\ndebug2: channel 2: rcvd close\r\ndebug3: channel 2: will not send data after close\r\ndebug2: channel 2: send close\r\ndebug3: send packet: type 97\r\ndebug2: channel 2: is dead\r\ndebug2: channel 2: gc: notify user\r\ndebug3: mux_master_session_cleanup_cb: entering for channel 2\r\ndebug2: channel 1: rcvd close\r\ndebug2: channel 1: output open -> drain\r\ndebug2: channel 1: close_read\r\ndebug2: channel 1: input open -> closed\r\ndebug2: channel 2: gc: user detached\r\ndebug2: channel 2: is dead\r\ndebug2: channel 2: garbage collecting\r\ndebug1: channel 2: free: client-session, nchannels 3\r\ndebug3: channel 2: status: The following connections are open:\r\n  #1 mux-control (t16 r-1 i3/0 o1/16 fd 6/6 cc -1)\r\n  #2 client-session (t4 r0 i3/0 o3/0 fd -1/-1 cc -1)\r\n\r\ndebug2: channel 1: obuf empty\r\ndebug2: channel 1: close_write\r\ndebug2: channel 1: output drain -> closed\r\ndebug2: channel 1: is dead (local)\r\ndebug2: channel 1: gc: notify user\r\ndebug3: mux_master_control_cleanup_cb: entering for channel 1\r\ndebug2: channel 1: gc: user detached\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: channel 1: is dead (local)\r\ndebug2: channel 1: garbage collecting\r\ndebug1: channel 1: free: mux-control, nchannels 2\r\ndebug2: Received exit status from master 0\r\ndebug3: channel 1: status: The following connections are open:\r\n  #1 mux-control (t16 r-1 i3/0 o3/0 fd 6/6 cc -1)\r\n\r\ndebug2: set_control_persist_exit_time: schedule exit in 60 seconds\r\n')
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 vm1.ipasrvtest001.net '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541 `" && echo ansible-tmp-1532094812.18-240253739158541="` echo /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541 `" ) && sleep 0'"'"''
<vm1.ipasrvtest001.net> (0, 'ansible-tmp-1532094812.18-240253739158541=/home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541\n', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
Using module file /usr/lib/python2.7/dist-packages/ansible/modules/system/setup.py
<vm1.ipasrvtest001.net> PUT /home/testcase/.ansible/tmp/ansible-local-31797j1iYOa/tmpu6uwWz TO /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541/setup.py
<vm1.ipasrvtest001.net> SSH: EXEC sftp -b - -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 '[vm1.ipasrvtest001.net]'
<vm1.ipasrvtest001.net> (0, 'sftp> put /home/testcase/.ansible/tmp/ansible-local-31797j1iYOa/tmpu6uwWz /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541/setup.py\n', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug2: Remote version: 3\r\ndebug2: Server supports extension "posix-rename@openssh.com" revision 1\r\ndebug2: Server supports extension "statvfs@openssh.com" revision 2\r\ndebug2: Server supports extension "fstatvfs@openssh.com" revision 2\r\ndebug2: Server supports extension "hardlink@openssh.com" revision 1\r\ndebug2: Server supports extension "fsync@openssh.com" revision 1\r\ndebug3: Sent message fd 5 T:16 I:1\r\ndebug3: SSH_FXP_REALPATH . -> /home/2mrrz18sfugo-ansible size 0\r\ndebug3: Looking up /home/testcase/.ansible/tmp/ansible-local-31797j1iYOa/tmpu6uwWz\r\ndebug3: Sent message fd 5 T:17 I:2\r\ndebug3: Received stat reply T:101 I:2\r\ndebug1: Couldn\'t stat remote file: No such file or directory\r\ndebug3: Sent message SSH2_FXP_OPEN I:3 P:/home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541/setup.py\r\ndebug3: Sent message SSH2_FXP_WRITE I:4 O:0 S:32768\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 4 32768 bytes at 0\r\ndebug3: Sent message SSH2_FXP_WRITE I:5 O:32768 S:32768\r\ndebug3: Sent message SSH2_FXP_WRITE I:6 O:65536 S:32768\r\ndebug3: Sent message SSH2_FXP_WRITE I:7 O:98304 S:32768\r\ndebug3: Sent message SSH2_FXP_WRITE I:8 O:131072 S:32768\r\ndebug3: Sent message SSH2_FXP_WRITE I:9 O:163840 S:32768\r\ndebug3: Sent message SSH2_FXP_WRITE I:10 O:196608 S:11483\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 5 32768 bytes at 32768\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 6 32768 bytes at 65536\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 7 32768 bytes at 98304\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 8 32768 bytes at 131072\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 9 32768 bytes at 163840\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 10 11483 bytes at 196608\r\ndebug3: Sent message SSH2_FXP_CLOSE I:4\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 vm1.ipasrvtest001.net '/bin/sh -c '"'"'chmod u+x /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541/ /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541/setup.py && sleep 0'"'"''
<vm1.ipasrvtest001.net> (0, '', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 -tt vm1.ipasrvtest001.net '/bin/sh -c '"'"'/usr/bin/python /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541/setup.py && sleep 0'"'"''
<vm1.ipasrvtest001.net> (0, '\r\n{"invocation": {"module_args": {"filter": "*", "gather_subset": ["all"], "fact_path": "/etc/ansible/facts.d", "gather_timeout": 10}}, "ansible_facts": {"ansible_product_serial": "NA", "ansible_form_factor": "Laptop", "ansible_user_gecos": "", "ansible_distribution_file_parsed": true, "ansible_fips": false, "ansible_service_mgr": "systemd", "ansible_user_id": "2mrrz18sfugo-ansible", "ansible_selinux_python_present": true, "ansible_userspace_bits": "64", "ansible_ssh_host_key_rsa_public": "AAAAB3NzaC1yc2EAAAADAQABAAABAQDlnCaDCzNf8pcEz6lOPyPt+JxyQX1SuNIIQnOA5pc0kQcvtO32e6SCPigzrBgxchfr5wP6lcueiZ925OqI8EqYC5RxqzrjgEfpxXdfhQLcGgoq6gsX+XpN7C3HqUNCCMSdUL79POwQDzv/IM87WtAMmhGpkCNTXWR4WaowTIFVg5/xg3JEt40ZQCj+/5NpP3qiZ+UE7kRDh7F7+Obneg8JCP1zpRcKt9RqqoBVR31b3sq9PvyXX2hsMbsc92gMpmLhMY6hL+mNPBrCTCeMW9I06ybwU5b7XWvIwD4xTClC4geKjCvKyAtXJQIIt2sEOJ6L4DyttDTyfgzVQCrtSWcT", "gather_subset": ["all"], "ansible_architecture": "x86_64", "ansible_local": {}, "ansible_distribution_version": "7.5.1804", "ansible_domain": "ipasrvtest001.net", "ansible_distribution_file_path": "/etc/redhat-release", "ansible_user_shell": "/bin/bash", "ansible_virtualization_type": "lxc-libvirt", "ansible_real_user_id": 1101, "ansible_processor_cores": 2, "ansible_virtualization_role": "guest", "ansible_distribution_file_variety": "RedHat", "ansible_dns": {"nameservers": ["192.168.100.1"], "search": ["local", "ipasrvtest001.net"]}, "ansible_effective_group_id": 1101, "ansible_is_chroot": true, "ansible_bios_version": "4.6.5", "ansible_processor": ["0", "GenuineIntel", "Intel(R) Core(TM) i5-4340M CPU @ 2.90GHz", "1", "GenuineIntel", "Intel(R) Core(TM) i5-4340M CPU @ 2.90GHz", "2", "GenuineIntel", "Intel(R) Core(TM) i5-4340M CPU @ 2.90GHz", "3", "GenuineIntel", "Intel(R) Core(TM) i5-4340M CPU @ 2.90GHz"], "ansible_date_time": {"weekday_number": "5", "iso8601_basic_short": "20180720T105333", "tz": "-03", "weeknumber": "29", "hour": "10", "year": "2018", "minute": "53", "tz_offset": "-0300", "month": "07", "epoch": "1532094813", "iso8601_micro": "2018-07-20T13:53:33.880501Z", "weekday": "Friday", "time": "10:53:33", "date": "2018-07-20", "iso8601": "2018-07-20T13:53:33Z", "day": "20", "iso8601_basic": "20180720T105333880442", "second": "33"}, "ansible_lo": {"mtu": 65536, "device": "lo", "promisc": false, "ipv4": {"broadcast": "host", "netmask": "255.0.0.0", "network": "127.0.0.0", "address": "127.0.0.1"}, "ipv6": [{"scope": "host", "prefix": "128", "address": "::1"}], "active": true, "type": "loopback"}, "ansible_memtotal_mb": 3855, "ansible_ssh_host_key_ecdsa_public": "AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBOXSbaduMmRm2aD9zA2Dh6hDcZ4XpJ/4NQnJ7EfQcOtpSsvNSG5hXSAVNsjzedvKSZgDM4nqvHHc1HNUcEbTRR4=", "ansible_device_links": {"masters": {"sda5": ["dm-0"], "dm-0": ["dm-1", "dm-2"]}, "labels": {}, "ids": {}, "uuids": {}}, "ansible_default_ipv4": {"macaddress": "00:16:3e:ec:21:ac", "network": "192.168.100.0", "mtu": 1500, "broadcast": "192.168.100.255", "alias": "eth0", "netmask": "255.255.255.0", "address": "192.168.100.138", "interface": "eth0", "type": "ether", "gateway": "192.168.100.1"}, "ansible_swapfree_mb": 2414, "ansible_default_ipv6": {}, "ansible_distribution_release": "Core", "ansible_system_vendor": "BANGHO", "ansible_os_family": "RedHat", "ansible_cmdline": {"BOOT_IMAGE": "/vmlinuz-4.10.0-38-generic", "quiet": true, "splash": true, "vt.handoff": "7", "ro": true, "root": "/dev/mapper/mint--vg-root"}, "ansible_effective_user_id": 1101, "ansible_user_gid": 1101, "ansible_selinux": {"status": "disabled"}, "ansible_product_version": "MAX G0101", "ansible_apparmor": {"status": "enabled"}, "ansible_userspace_architecture": "x86_64", "ansible_product_uuid": "NA", "ansible_fqdn": "vm1.ipasrvtest001.net", "ansible_system": "Linux", "ansible_pkg_mgr": "yum", "ansible_memfree_mb": 120, "ansible_devices": {"dm-2": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "8200192", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": null, "partitions": {}, "holders": [], "size": "3.91 GB"}, "loop4": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "0", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": null, "partitions": {}, "holders": [], "size": "0.00 Bytes"}, "sr0": {"scheduler_mode": "cfq", "rotational": "1", "vendor": "TSSTcorp", "sectors": "2097151", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "1", "support_discard": "0", "model": "CDDVDW SU-208FB", "partitions": {}, "holders": [], "size": "1024.00 MB"}, "dm-0": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "975765504", "links": {"masters": ["dm-1", "dm-2"], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": null, "partitions": {}, "holders": ["mint--vg-root", "mint--vg-swap_1"], "size": "465.28 GB"}, "sda": {"scheduler_mode": "cfq", "rotational": "1", "vendor": "ATA", "sectors": "976773168", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": "WDC WD5000LPCX-2", "partitions": {"sda5": {"sectorsize": 512, "uuid": null, "links": {"masters": ["dm-0"], "labels": [], "ids": [], "uuids": []}, "sectors": "975769600", "start": "1001472", "holders": ["sda5_crypt"], "size": "465.28 GB"}, "sda2": {"sectorsize": 512, "uuid": null, "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sectors": "2", "start": "1001470", "holders": [], "size": "1.00 KB"}, "sda1": {"sectorsize": 512, "uuid": null, "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sectors": "997376", "start": "2048", "holders": [], "size": "487.00 MB"}}, "holders": [], "size": "465.76 GB"}, "loop3": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "0", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": null, "partitions": {}, "holders": [], "size": "0.00 Bytes"}, "loop2": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "0", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": null, "partitions": {}, "holders": [], "size": "0.00 Bytes"}, "loop1": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "20971520", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "4096", "model": null, "partitions": {}, "holders": [], "size": "10.00 GB"}, "loop0": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "20971520", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "4096", "model": null, "partitions": {}, "holders": [], "size": "10.00 GB"}, "loop7": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "0", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": null, "partitions": {}, "holders": [], "size": "0.00 Bytes"}, "loop6": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "0", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": null, "partitions": {}, "holders": [], "size": "0.00 Bytes"}, "loop5": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "0", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": null, "partitions": {}, "holders": [], "size": "0.00 Bytes"}, "dm-1": {"scheduler_mode": "", "rotational": "1", "vendor": null, "sectors": "967557120", "links": {"masters": [], "labels": [], "ids": [], "uuids": []}, "sas_device_handle": null, "sas_address": null, "virtual": 1, "host": "", "sectorsize": "512", "removable": "0", "support_discard": "0", "model": null, "partitions": {}, "holders": [], "size": "461.37 GB"}}, "ansible_user_uid": 1101, "ansible_memory_mb": {"real": {"total": 3855, "used": 3735, "free": 120}, "swap": {"cached": 198, "total": 4003, "free": 2414, "used": 1589}, "nocache": {"used": 2629, "free": 1226}}, "ansible_distribution": "CentOS", "ansible_user_dir": "/home/2mrrz18sfugo-ansible", "ansible_env": {"LC_NUMERIC": "es_AR.UTF-8", "LESSOPEN": "||/usr/bin/lesspipe.sh %s", "SSH_CLIENT": "192.168.100.137 45706 22", "LOGNAME": "2mrrz18sfugo-ansible", "USER": "2mrrz18sfugo-ansible", "PATH": "/usr/local/bin:/usr/bin", "LC_PAPER": "es_AR.UTF-8", "HOME": "/home/2mrrz18sfugo-ansible", "LANG": "en_US.UTF-8", "TERM": "xterm-256color", "SHELL": "/bin/bash", "LC_MEASUREMENT": "es_AR.UTF-8", "LC_MONETARY": "es_AR.UTF-8", "XDG_RUNTIME_DIR": "/run/user/1101", "LC_ADDRESS": "es_AR.UTF-8", "XDG_SESSION_ID": "c74", "_": "/usr/bin/python", "LC_IDENTIFICATION": "es_AR.UTF-8", "LS_COLORS": "rs=0:di=38;5;27:ln=38;5;51:mh=44;38;5;15:pi=40;38;5;11:so=38;5;13:do=38;5;5:bd=48;5;232;38;5;11:cd=48;5;232;38;5;3:or=48;5;232;38;5;9:mi=05;48;5;232;38;5;15:su=48;5;196;38;5;15:sg=48;5;11;38;5;16:ca=48;5;196;38;5;226:tw=48;5;10;38;5;16:ow=48;5;10;38;5;21:st=48;5;21;38;5;15:ex=38;5;34:*.tar=38;5;9:*.tgz=38;5;9:*.arc=38;5;9:*.arj=38;5;9:*.taz=38;5;9:*.lha=38;5;9:*.lz4=38;5;9:*.lzh=38;5;9:*.lzma=38;5;9:*.tlz=38;5;9:*.txz=38;5;9:*.tzo=38;5;9:*.t7z=38;5;9:*.zip=38;5;9:*.z=38;5;9:*.Z=38;5;9:*.dz=38;5;9:*.gz=38;5;9:*.lrz=38;5;9:*.lz=38;5;9:*.lzo=38;5;9:*.xz=38;5;9:*.bz2=38;5;9:*.bz=38;5;9:*.tbz=38;5;9:*.tbz2=38;5;9:*.tz=38;5;9:*.deb=38;5;9:*.rpm=38;5;9:*.jar=38;5;9:*.war=38;5;9:*.ear=38;5;9:*.sar=38;5;9:*.rar=38;5;9:*.alz=38;5;9:*.ace=38;5;9:*.zoo=38;5;9:*.cpio=38;5;9:*.7z=38;5;9:*.rz=38;5;9:*.cab=38;5;9:*.jpg=38;5;13:*.jpeg=38;5;13:*.gif=38;5;13:*.bmp=38;5;13:*.pbm=38;5;13:*.pgm=38;5;13:*.ppm=38;5;13:*.tga=38;5;13:*.xbm=38;5;13:*.xpm=38;5;13:*.tif=38;5;13:*.tiff=38;5;13:*.png=38;5;13:*.svg=38;5;13:*.svgz=38;5;13:*.mng=38;5;13:*.pcx=38;5;13:*.mov=38;5;13:*.mpg=38;5;13:*.mpeg=38;5;13:*.m2v=38;5;13:*.mkv=38;5;13:*.webm=38;5;13:*.ogm=38;5;13:*.mp4=38;5;13:*.m4v=38;5;13:*.mp4v=38;5;13:*.vob=38;5;13:*.qt=38;5;13:*.nuv=38;5;13:*.wmv=38;5;13:*.asf=38;5;13:*.rm=38;5;13:*.rmvb=38;5;13:*.flc=38;5;13:*.avi=38;5;13:*.fli=38;5;13:*.flv=38;5;13:*.gl=38;5;13:*.dl=38;5;13:*.xcf=38;5;13:*.xwd=38;5;13:*.yuv=38;5;13:*.cgm=38;5;13:*.emf=38;5;13:*.axv=38;5;13:*.anx=38;5;13:*.ogv=38;5;13:*.ogx=38;5;13:*.aac=38;5;45:*.au=38;5;45:*.flac=38;5;45:*.mid=38;5;45:*.midi=38;5;45:*.mka=38;5;45:*.mp3=38;5;45:*.mpc=38;5;45:*.ogg=38;5;45:*.ra=38;5;45:*.wav=38;5;45:*.axa=38;5;45:*.oga=38;5;45:*.spx=38;5;45:*.xspf=38;5;45:", "SSH_TTY": "/dev/pts/2", "LC_TELEPHONE": "es_AR.UTF-8", "SHLVL": "2", "PWD": "/home/2mrrz18sfugo-ansible", "LC_NAME": "es_AR.UTF-8", "MAIL": "/var/mail/2mrrz18sfugo-ansible", "SSH_CONNECTION": "192.168.100.137 45706 192.168.100.138 22"}, "ansible_distribution_major_version": "7", "module_setup": true, "ansible_processor_count": 1, "ansible_hostname": "vm1", "ansible_processor_vcpus": 4, "ansible_swaptotal_mb": 4003, "ansible_lsb": {}, "ansible_real_group_id": 1101, "ansible_bios_date": "08/08/2014", "ansible_all_ipv6_addresses": ["fe80::216:3eff:feec:21ac"], "ansible_interfaces": ["lo", "eth0"], "ansible_uptime_seconds": 127024, "ansible_machine_id": "07559a1ebfd64f73a284a1f101c7c88d", "ansible_kernel": "4.10.0-38-generic", "ansible_iscsi_iqn": "", "ansible_system_capabilities_enforced": "True", "ansible_python": {"executable": "/usr/bin/python", "version": {"micro": 5, "major": 2, "releaselevel": "final", "serial": 0, "minor": 7}, "type": "CPython", "has_sslcontext": true, "version_info": [2, 7, 5, "final", 0]}, "ansible_processor_threads_per_core": 2, "ansible_ssh_host_key_ed25519_public": "AAAAC3NzaC1lZDI1NTE5AAAAIOEPYrXj3nYyxYekVbFKoJJw91zkMXaVUrQ3/5DjB87v", "ansible_mounts": [{"block_used": 309292, "uuid": "N/A", "size_total": 10726932480, "block_total": 2618880, "mount": "/", "block_available": 2309588, "size_available": 9460072448, "fstype": "xfs", "inode_total": 5242880, "options": "rw,relatime,attr2,inode64,noquota", "device": "/dev/loop0", "inode_used": 32573, "block_size": 4096, "inode_available": 5210307}, {"block_used": 20397495, "uuid": "N/A", "size_total": 487481991168, "block_total": 119014158, "mount": "/home/testcase", "block_available": 98616663, "size_available": 403933851648, "fstype": "ext4", "inode_total": 30236672, "options": "rw,relatime,errors=remount-ro,data=ordered,bind", "device": "/dev/mapper/mint--vg-root", "inode_used": 1848000, "block_size": 4096, "inode_available": 28388672}], "ansible_eth0": {"macaddress": "00:16:3e:ec:21:ac", "type": "ether", "mtu": 1500, "device": "eth0", "promisc": false, "ipv4": {"broadcast": "192.168.100.255", "netmask": "255.255.255.0", "network": "192.168.100.0", "address": "192.168.100.138"}, "ipv6": [{"scope": "link", "prefix": "64", "address": "fe80::216:3eff:feec:21ac"}], "active": true, "speed": 10000}, "ansible_nodename": "vm1.ipasrvtest001.net", "ansible_product_name": "MAX G0101", "ansible_machine": "x86_64", "ansible_system_capabilities": [""], "ansible_all_ipv4_addresses": ["192.168.100.138"], "ansible_python_version": "2.7.5"}}\r\n', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\nShared connection to vm1.ipasrvtest001.net closed.\r\n')
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 vm1.ipasrvtest001.net '/bin/sh -c '"'"'rm -f -r /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094812.18-240253739158541/ > /dev/null 2>&1 && sleep 0'"'"''
<vm1.ipasrvtest001.net> (0, '', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
ok: [vm1.ipasrvtest001.net]
META: ran handlers

TASK [Create a test freeipa group ] **************************************************************************************************
task path: /home/testcase/playbooks/testgroup.yml:15
Trying secret FileVaultSecret(filename='/home/testcase/.vaultpass') for vault_id=default
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 vm1.ipasrvtest001.net '/bin/sh -c '"'"'echo ~2mrrz18sfugo-ansible && sleep 0'"'"''
<vm1.ipasrvtest001.net> (0, '/home/2mrrz18sfugo-ansible\n', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 vm1.ipasrvtest001.net '/bin/sh -c '"'"'( umask 77 && mkdir -p "` echo /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534 `" && echo ansible-tmp-1532094814.64-104953578334534="` echo /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534 `" ) && sleep 0'"'"''
<vm1.ipasrvtest001.net> (0, 'ansible-tmp-1532094814.64-104953578334534=/home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534\n', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
Using module file /usr/lib/python2.7/dist-packages/ansible/modules/identity/ipa/ipa_group.py
<vm1.ipasrvtest001.net> PUT /home/testcase/.ansible/tmp/ansible-local-31797j1iYOa/tmp6qVYZq TO /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534/ipa_group.py
<vm1.ipasrvtest001.net> SSH: EXEC sftp -b - -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 '[vm1.ipasrvtest001.net]'
<vm1.ipasrvtest001.net> (0, 'sftp> put /home/testcase/.ansible/tmp/ansible-local-31797j1iYOa/tmp6qVYZq /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534/ipa_group.py\n', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug2: Remote version: 3\r\ndebug2: Server supports extension "posix-rename@openssh.com" revision 1\r\ndebug2: Server supports extension "statvfs@openssh.com" revision 2\r\ndebug2: Server supports extension "fstatvfs@openssh.com" revision 2\r\ndebug2: Server supports extension "hardlink@openssh.com" revision 1\r\ndebug2: Server supports extension "fsync@openssh.com" revision 1\r\ndebug3: Sent message fd 5 T:16 I:1\r\ndebug3: SSH_FXP_REALPATH . -> /home/2mrrz18sfugo-ansible size 0\r\ndebug3: Looking up /home/testcase/.ansible/tmp/ansible-local-31797j1iYOa/tmp6qVYZq\r\ndebug3: Sent message fd 5 T:17 I:2\r\ndebug3: Received stat reply T:101 I:2\r\ndebug1: Couldn\'t stat remote file: No such file or directory\r\ndebug3: Sent message SSH2_FXP_OPEN I:3 P:/home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534/ipa_group.py\r\ndebug3: Sent message SSH2_FXP_WRITE I:4 O:0 S:32768\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 4 32768 bytes at 0\r\ndebug3: Sent message SSH2_FXP_WRITE I:5 O:32768 S:32768\r\ndebug3: Sent message SSH2_FXP_WRITE I:6 O:65536 S:24087\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 5 32768 bytes at 32768\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: In write loop, ack for 6 24087 bytes at 65536\r\ndebug3: Sent message SSH2_FXP_CLOSE I:4\r\ndebug3: SSH2_FXP_STATUS 0\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 vm1.ipasrvtest001.net '/bin/sh -c '"'"'chmod u+x /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534/ /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534/ipa_group.py && sleep 0'"'"''
<vm1.ipasrvtest001.net> (0, '', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 -tt vm1.ipasrvtest001.net '/bin/sh -c '"'"'/usr/bin/python /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534/ipa_group.py && sleep 0'"'"''
<vm1.ipasrvtest001.net> (1, '\r\n{"msg": "login: HTTP Error 400: Bad Request", "failed": true, "invocation": {"module_args": {"ipa_port": 443, "group": null, "description": "Group created by the testgroup ansible playbook", "external": null, "ipa_prot": "https", "state": "present", "gidnumber": null, "ipa_host": "vm1", "user": null, "ipa_user": "admin", "ipa_pass": "VALUE_SPECIFIED_IN_NO_LOG_PARAMETER", "validate_certs": false, "nonposix": null, "cn": "testcase"}}}\r\n', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 1\r\nShared connection to vm1.ipasrvtest001.net closed.\r\n')
<vm1.ipasrvtest001.net> ESTABLISH SSH CONNECTION FOR USER: 2mrrz18sfugo-ansible
<vm1.ipasrvtest001.net> SSH: EXEC ssh -vvv -C -o ControlMaster=auto -o ControlPersist=60s -o Port=22 -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o User=2mrrz18sfugo-ansible -o ConnectTimeout=10 -o ControlPath=/home/testcase/.ansible/cp/64f97777f2 vm1.ipasrvtest001.net '/bin/sh -c '"'"'rm -f -r /home/2mrrz18sfugo-ansible/.ansible/tmp/ansible-tmp-1532094814.64-104953578334534/ > /dev/null 2>&1 && sleep 0'"'"''
<vm1.ipasrvtest001.net> (0, '', 'OpenSSH_7.4p1 Debian-10+deb9u3, OpenSSL 1.0.2l  25 May 2017\r\ndebug1: Reading configuration data /etc/ssh/ssh_config\r\ndebug1: /etc/ssh/ssh_config line 19: Applying options for *\r\ndebug1: auto-mux: Trying existing master\r\ndebug2: fd 3 setting O_NONBLOCK\r\ndebug2: mux_client_hello_exchange: master version 4\r\ndebug3: mux_client_forwards: request forwardings: 0 local, 0 remote\r\ndebug3: mux_client_request_session: entering\r\ndebug3: mux_client_request_alive: entering\r\ndebug3: mux_client_request_alive: done pid = 31811\r\ndebug3: mux_client_request_session: session request sent\r\ndebug1: mux_client_request_session: master session id: 2\r\ndebug3: mux_client_read_packet: read header failed: Broken pipe\r\ndebug2: Received exit status from master 0\r\n')
fatal: [vm1.ipasrvtest001.net]: FAILED! => {
    "changed": false, 
    "invocation": {
        "module_args": {
            "cn": "testgroup", 
            "description": "Test group", 
            "external": null, 
            "gidnumber": null, 
            "group": null, 
            "ipa_host": "vm1", 
            "ipa_pass": "VALUE_SPECIFIED_IN_NO_LOG_PARAMETER", 
            "ipa_port": 443, 
            "ipa_prot": "https", 
            "ipa_user": "admin", 
            "nonposix": null, 
            "state": "present", 
            "user": null, 
            "validate_certs": false
        }
    }, 
    "msg": "login: HTTP Error 400: Bad Request"
}
	to retry, use: --limit @/home/testcase/playbooks/testgroup.retry

PLAY RECAP ***************************************************************************************************************************
vm1.ipasrvtest001.net           : ok=2    changed=0    unreachable=0    failed=1  
@ansibot
Copy link
Contributor

ansibot commented Jul 20, 2018

Files identified in the description:

If these files are inaccurate, please update the component name section of the description or use the !component bot command.

click here for bot help

@ansibot
Copy link
Contributor

ansibot commented Jul 20, 2018

@ansibot ansibot added affects_2.6 This issue/PR affects Ansible v2.6 bug This issue/PR relates to a bug. module This issue/PR relates to a module. needs_triage Needs a first human triage before being processed. support:community This issue/PR relates to code supported by the Ansible community. support:core This issue/PR relates to code supported by the Ansible Engineering Team. labels Jul 20, 2018
@Akasurde Akasurde removed the needs_triage Needs a first human triage before being processed. label Jul 20, 2018
@Akasurde
Copy link
Member

@Guampa Thanks for reporting this issue. Could you please check if vm1.ipatest001.net resolvable or not? I had same issue, you can check comment here - https://bugzilla.redhat.com/show_bug.cgi?id=1451768#c6

@3coma3
Copy link
Author

3coma3 commented Jul 20, 2018

Hello @Akasurde , thank you for your prompt response.

Yes, I did check and it is reachable. I've also verified with a small shell snippet that the server is authenticating correctly:

$  scripts/testipaauth.sh $$pa$$word
Note: Unnecessary use of -X or --request, POST is already inferred.
*   Trying 192.168.100.138...
* Connected to vm1.ipasrvtest001.net (192.168.100.138) port 443 (#0)
* error reading ca cert file /etc/ipa/ca.crt (Error while reading file.)
* found 598 certificates in /etc/ssl/certs
* ALPN, offering http/1.1
* SSL connection using TLS1.2 / ECDHE_RSA_AES_256_GCM_SHA384
* 	 server certificate verification SKIPPED
* 	 server certificate status verification SKIPPED
* 	 common name: vm1.ipasrvtest001.net (matched)
* 	 server certificate expiration date OK
* 	 server certificate activation date OK
* 	 certificate public key: RSA
* 	 certificate version: #3
* 	 subject: O=IPASRVTEST001.NET,CN=vm1.ipasrvtest001.net
* 	 start date: Fri, 20 Jul 2018 20:44:34 GMT
* 	 expire date: Mon, 20 Jul 2020 20:44:34 GMT
* 	 issuer: O=IPASRVTEST001.NET,CN=Certificate Authority
* 	 compression: NULL
* ALPN, server did not agree to a protocol
> POST /ipa/session/login_password HTTP/1.1
> Host: vm1.ipasrvtest001.net
> User-Agent: curl/7.47.0
> Referer:https://vm1.ipasrvtest001.net/ipa
> Content-Type:application/x-www-form-urlencoded
> Accept:text/plain
> Content-Length: 32
> 
* upload completely sent off: 32 out of 32 bytes
< HTTP/1.1 200 Success
< Date: Fri, 20 Jul 2018 21:06:08 GMT
< Server: Apache/2.4.6 (CentOS) mod_auth_gssapi/1.5.1 mod_nss/1.0.14 NSS/3.28.4 mod_wsgi/3.4 Python/2.7.5
< IPASESSION: MagBearerToken=clzOAXYc4%2bpP39WFPCNkwFmAqG%2b7gnhJGVDeQ1AmzOeRj%2bmFPiqnoGtJTItyE5VcyhiePtR8ZVZQf5Tv1EamYxQbsxBrMMovywPM31IONDJMncVe0hSpGRmNWUBVqP%2ftfY%2bw81yJOTrOhhbPA2uaoLw80UZA3IM5UH15d9tB5XChPOwj8B6HhRJg9p8eivVnUVtzAhZZ2zlPESf1kie%2b2A%3d%3d
* Added cookie ipa_session="MagBearerToken=clzOAXYc4%2bpP39WFPCNkwFmAqG%2b7gnhJGVDeQ1AmzOeRj%2bmFPiqnoGtJTItyE5VcyhiePtR8ZVZQf5Tv1EamYxQbsxBrMMovywPM31IONDJMncVe0hSpGRmNWUBVqP%2ftfY%2bw81yJOTrOhhbPA2uaoLw80UZA3IM5UH15d9tB5XChPOwj8B6HhRJg9p8eivVnUVtzAhZZ2zlPESf1kie%2b2A%3d%3d" for domain vm1.ipasrvtest001.net, path /ipa, expire 0
< Set-Cookie: ipa_session=MagBearerToken=clzOAXYc4%2bpP39WFPCNkwFmAqG%2b7gnhJGVDeQ1AmzOeRj%2bmFPiqnoGtJTItyE5VcyhiePtR8ZVZQf5Tv1EamYxQbsxBrMMovywPM31IONDJMncVe0hSpGRmNWUBVqP%2ftfY%2bw81yJOTrOhhbPA2uaoLw80UZA3IM5UH15d9tB5XChPOwj8B6HhRJg9p8eivVnUVtzAhZZ2zlPESf1kie%2b2A%3d%3d;path=/ipa;httponly;secure;
< X-Frame-Options: DENY
< Content-Security-Policy: frame-ancestors 'none'
< Cache-Control: no-cache
< Content-Length: 0
< Content-Type: text/plain; charset=UTF-8
< 
* Connection #0 to host vm1.ipasrvtest001.net left intact

testipaauth.sh

#!/bin/bash
curl -v  \
-H Referer:https://vm1.ipasrvtest001.net/ipa  \
-H "Content-Type:application/x-www-form-urlencoded" \
-H "Accept:text/plain" \
-c $COOKIEJAR -b $COOKIEJAR \
--cacert /etc/ipa/ca.crt  \
--data "user=admin&password=$1" \
-X POST \
-k \
https://vm1.ipasrvtest001.net/ipa/session/login_password

@Akasurde
Copy link
Member

resolved_by_pr #43133

abadger pushed a commit to abadger/ansible that referenced this issue Jul 23, 2018
@3coma3
Copy link
Author

3coma3 commented Jul 23, 2018

Thanks a lot guys, waiting for the backport and in the meantime I'll resort to a custom module to talk to FreeIPA.

@ansibot ansibot closed this as completed Jul 23, 2018
@Akasurde
Copy link
Member

@Guampa Could you please check if this works with / without this patch #43133 in 2.6 branch ?

@3coma3
Copy link
Author

3coma3 commented Jul 23, 2018

@Akasurde the patch won't apply at the same line in 2.6 but it's a single line so I added it manually. The error still persists, both with and without the patch.

@@ -826,6 +826,7 @@
 
     Does not require the module environment
     '''
+    method = method or ('POST' if data else 'GET')
 
     handlers = []
     ssl_handler = maybe_add_ssl_handler(url, validate_certs)

@Akasurde Akasurde reopened this Jul 23, 2018
@Akasurde
Copy link
Member

bot_skip

@Akasurde
Copy link
Member

@Guampa I see you are using short name as hostname in your invocation -

fatal: [vm1.ipasrvtest001.net]: FAILED! => {
    "changed": false, 
    "invocation": {
        "module_args": {
            "cn": "testgroup", 
            "description": "Test group", 
            "external": null, 
            "gidnumber": null, 
            "group": null, 
            "ipa_host": "vm1",  ### <---- here

I can reproduce this using 2.6

fatal: [localhost]: FAILED! => {
    "changed": false,
    "invocation": {
        "module_args": {
            "displayname": null,
            "gidnumber": null,
            "givenname": "Pinky5",
            "ipa_host": "master",
            "ipa_pass": "VALUE_SPECIFIED_IN_NO_LOG_PARAMETER",
            "ipa_port": 443,
            "ipa_prot": "https",
            "ipa_user": "admin",
            "krbpasswordexpiration": null,
            "loginshell": null,
            "mail": null,
            "name": "pinky5",
            "password": null,
            "sn": "pinky5",
            "sshpubkey": null,
            "state": "present",
            "telephonenumber": null,
            "title": null,
            "uid": "pinky5",
            "uidnumber": null,
            "validate_certs": false
        }
    },
    "msg": "login: HTTP Error 400: Bad Request"
}

with playbook -

---
- name: Error 43091
  hosts: localhost
  tasks:
    - name: Add user
      ipa_user:
        ipa_pass: Secret1234
        ipa_host: master
        ipa_user: admin
        validate_certs: false
        name: pinky5
        state: present
        givenname: Pinky5
        sn: pinky5

but if I provide FQDN of IPA Master server then I can proceed -

changed: [localhost] => {
    "changed": true,
    "invocation": {
        "module_args": {
            "displayname": null,
            "gidnumber": null,
            "givenname": "Pinky5",
            "ipa_host": "master.ipa.test", ### <--- FQDN of IPA master
            "ipa_pass": "VALUE_SPECIFIED_IN_NO_LOG_PARAMETER",
            "ipa_port": 443,
            "ipa_prot": "https",
            "ipa_user": "admin",
            "krbpasswordexpiration": null,
            "loginshell": null,
            "mail": null,
            "name": "pinky5",
            "password": null,
            "sn": "pinky5",
            "sshpubkey": null,
            "state": "present",
            "telephonenumber": null,
            "title": null,
            "uid": "pinky5",
            "uidnumber": null,
            "validate_certs": false
        }
    },

with playbook

---
- name: Error 43091
  hosts: localhost
  tasks:
    - name: Add user
      ipa_user:
        ipa_pass: Secret1234
        ipa_host: master.ipa.test
        ipa_user: admin
        validate_certs: false
        name: pinky5
        state: present
        givenname: Pinky5
        sn: pinky5

This brings me to conclusion that this is related to FQDN and nothing to do with #43133 patch. Could you please provide full FQDN for IPA server and retry ?

@3coma3
Copy link
Author

3coma3 commented Jul 24, 2018

@Akasurde, you hit the nail.

I was indeed using a FQDN, but the name was only resolvable at the host where the task was to be run (set in /etc/hosts). When I changed ipa_host to the "real" FQDN of the ipa server (resolvable recursively over public DNS), it did work.

What prevented me from finding this was the fact the "testipaauth" shell script was succeeding with the "fake" FQDN. Maybe it's some sort of constraint?

However the detail above it does look like it has nothing to do with the HTTP method, and that the correct POST is being used, so #43133 probably isn't needed here.

@Akasurde
Copy link
Member

@Guampa Cool. I am glad this worked for you. I will mark this issue as resolved as this is more related to environment rather than Ansible module issue. Thanks for debugging and providing feedback.

@dagwieers dagwieers added the ipa IPA community label Feb 8, 2019
@ansible ansible locked and limited conversation to collaborators Jul 22, 2019
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
affects_2.6 This issue/PR affects Ansible v2.6 bug This issue/PR relates to a bug. ipa IPA community module This issue/PR relates to a module. support:community This issue/PR relates to code supported by the Ansible community. support:core This issue/PR relates to code supported by the Ansible Engineering Team.
Projects
None yet
Development

No branches or pull requests

4 participants