Skip to content

Instantly share code, notes, and snippets.

@oliver

oliver/ansible-debug-log.txt Secret

Last active March 20, 2016 09:43
Show Gist options
  • Save oliver/e5a24a5b37c0006bb220 to your computer and use it in GitHub Desktop.
Save oliver/e5a24a5b37c0006bb220 to your computer and use it in GitHub Desktop.
Download ZIP
error with Ansible 2.0.1.0-1ppa~precise, on Ubuntu 12.04, connecting to Debian 8.2 VM, using become_method=su, with $LANG set to empty string
Raw
ansible-debug-log.txt
oliver:~/devel/vserver/ansible$ ANSIBLE_DEBUG=y LANG= ansible all -m ping -vvvvv
14117 1458466821.08207: starting run
Using /home/oliver/devel/vserver/ansible/ansible.cfg as config file
Loaded callback minimal of type stdout, v2.0
14117 1458466821.29262: in VariableManager get_vars()
14117 1458466821.29287: done with get_vars()
14117 1458466821.30384: getting the remaining hosts for this loop
14117 1458466821.30401: done getting the remaining hosts for this loop
14117 1458466821.30422: building list of next tasks for hosts
14117 1458466821.30427: getting the next task for host vs5vb
14117 1458466821.30435: done getting next task for host vs5vb
14117 1458466821.30440: ^ task is: TASK: meta
14117 1458466821.30444: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, role=None, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? None, rescue child state? None, always child state? None
14117 1458466821.30448: done building task lists
14117 1458466821.30451: counting tasks in each state of execution
14117 1458466821.30454: done counting tasks in each state of execution
14117 1458466821.30457: advancing hosts in ITERATING_TASKS
14117 1458466821.30460: starting to advance hosts
14117 1458466821.30463: getting the next task for host vs5vb
14117 1458466821.30468: done getting next task for host vs5vb
14117 1458466821.30471: ^ task is: TASK: meta
14117 1458466821.30474: ^ state is: HOST STATE: block=1, task=1, rescue=0, always=0, role=None, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? None, rescue child state? None, always child state? None
14117 1458466821.30477: done advancing hosts to next task
14117 1458466821.30664: done queuing things up, now waiting for results queue to drain
14117 1458466821.30669: waiting for pending results...
14117 1458466821.30673: no more pending results, returning what we have
14117 1458466821.30677: results queue empty
14117 1458466821.30679: checking for any_errors_fatal
14117 1458466821.30682: done checking for any_errors_fatal
14117 1458466821.30685: getting the remaining hosts for this loop
14117 1458466821.30690: done getting the remaining hosts for this loop
14117 1458466821.30711: building list of next tasks for hosts
14117 1458466821.30715: getting the next task for host vs5vb
14117 1458466821.30723: done getting next task for host vs5vb
14117 1458466821.30727: ^ task is: TASK: ping
14117 1458466821.30731: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, role=None, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? None, rescue child state? None, always child state? None
14117 1458466821.30734: done building task lists
14117 1458466821.30737: counting tasks in each state of execution
14117 1458466821.30741: done counting tasks in each state of execution
14117 1458466821.30744: advancing hosts in ITERATING_TASKS
14117 1458466821.30746: starting to advance hosts
14117 1458466821.30750: getting the next task for host vs5vb
14117 1458466821.30756: done getting next task for host vs5vb
14117 1458466821.30760: ^ task is: TASK: ping
14117 1458466821.30764: ^ state is: HOST STATE: block=2, task=1, rescue=0, always=0, role=None, run_state=ITERATING_TASKS, fail_state=FAILED_NONE, pending_setup=False, tasks child state? None, rescue child state? None, always child state? None
14117 1458466821.30767: done advancing hosts to next task
14117 1458466821.30776: getting variables
14117 1458466821.30780: in VariableManager get_vars()
14117 1458466821.30812: done with get_vars()
14117 1458466821.30820: done getting variables
14117 1458466821.30825: sending task start callback, copying the task so we can template it temporarily
14117 1458466821.30828: done copying, going to template now
14117 1458466821.30832: done templating
14117 1458466821.30834: here goes the callback...
14117 1458466821.30839: sending task start callback
14117 1458466821.30843: entering _queue_task() for vs5vb/TASK: ping
14117 1458466821.30847: vs5vb - putting task (TASK: ping) in queue
14117 1458466821.30850: worker is 1 (out of 1 available)
14117 1458466821.30969: exiting _queue_task() for vs5vb/TASK: ping
14117 1458466821.30996: done queuing things up, now waiting for results queue to drain
14117 1458466821.31001: waiting for pending results...
14131 1458466821.31917: running TaskExecutor() for vs5vb/TASK: ping
14131 1458466821.31950: in run()
14131 1458466821.32099: calling self._execute()
14131 1458466821.35560: starting attempt loop
14131 1458466821.35570: running the handler
14131 1458466821.35681: _low_level_execute_command(): starting
14131 1458466821.35687: _low_level_execute_command(): executing: /bin/sh -c '( umask 22 && mkdir -p "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" && echo "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" )'
<vs5vb> ESTABLISH SSH CONNECTION FOR USER: None
<vs5vb> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=60s)
<vs5vb> SSH: ansible_password/ansible_ssh_pass not set: (-o)(KbdInteractiveAuthentication=no)(-o)(PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey)(-o)(PasswordAuthentication=no)
<vs5vb> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10)
<vs5vb> SSH: PlayContext set ssh_common_args: ()
<vs5vb> SSH: PlayContext set ssh_extra_args: ()
<vs5vb> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/home/oliver/.ansible/cp/ansible-ssh-%h-%p-%r)
<vs5vb> SSH: EXEC ssh -C -vvv -o ControlMaster=auto -o ControlPersist=60s -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o ConnectTimeout=10 -o ControlPath=/home/oliver/.ansible/cp/ansible-ssh-%h-%p-%r -tt vs5vb '/bin/sh -c '"'"'( umask 22 && mkdir -p "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" && echo "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" )'"'"''
14131 1458466821.36777: stderr chunk (state=2):
>>>OpenSSH_5.9p1 Debian-5ubuntu1.8, OpenSSL 1.0.1 14 Mar 2012
<<<
14131 1458466821.37514: stderr chunk (state=3):
>>>debug1: Reading configuration data /home/oliver/.ssh/config
<<<
14131 1458466821.37558: stderr chunk (state=3):
>>>debug1: Reading configuration data /etc/ssh/ssh_config
<<<
14131 1458466821.37572: stderr chunk (state=3):
>>>debug1: /etc/ssh/ssh_config line 19: Applying options for *
<<<
14131 1458466821.37663: stderr chunk (state=3):
>>>debug1: auto-mux: Trying existing master
debug1: Control socket "/home/oliver/.ansible/cp/ansible-ssh-vs5vb-22-oliver" does not exist
debug2: ssh_connect: needpriv 0
<<<
14131 1458466821.38053: stderr chunk (state=3):
>>>debug1: Connecting to vs5vb [192.168.0.27] port 22.
debug2: fd 3 setting O_NONBLOCK
<<<
14131 1458466821.38472: stderr chunk (state=3):
>>>debug1: fd 3 clearing O_NONBLOCK
debug1: Connection established.
debug3: timeout: 9997 ms remain after connect
debug3: Incorrect RSA1 identifier
debug3: Could not load "/home/oliver/.ssh/id_rsa" as a RSA1 public key
debug1: identity file /home/oliver/.ssh/id_rsa type 1
debug1: Checking blacklist file /usr/share/ssh/blacklist.RSA-2048
debug1: Checking blacklist file /etc/ssh/blacklist.RSA-2048
debug1: identity file /home/oliver/.ssh/id_rsa-cert type -1
debug1: identity file /home/oliver/.ssh/id_dsa type -1
debug1: identity file /home/oliver/.ssh/id_dsa-cert type -1
debug1: identity file /home/oliver/.ssh/id_ecdsa type -1
debug1: identity file /home/oliver/.ssh/id_ecdsa-cert type -1
<<<
14131 1458466821.39652: stderr chunk (state=3):
>>>debug1: Remote protocol version 2.0, remote software version OpenSSH_6.7p1 Debian-5+deb8u1
debug1: match: OpenSSH_6.7p1 Debian-5+deb8u1 pat OpenSSH*
debug1: Enabling compatibility mode for protocol 2.0
<<<
14131 1458466821.39686: stderr chunk (state=3):
>>>debug1: Local version string SSH-2.0-OpenSSH_5.9p1 Debian-5ubuntu1.8
debug2: fd 3 setting O_NONBLOCK
debug3: load_hostkeys: loading entries for host "vs5vb" from file "/home/oliver/.ssh/known_hosts"
<<<
14131 1458466821.40215: stderr chunk (state=3):
>>>debug3: load_hostkeys: found key type ECDSA in file /home/oliver/.ssh/known_hosts:82
debug3: load_hostkeys: loaded 1 keys
<<<
14131 1458466821.40249: stderr chunk (state=3):
>>>debug3: 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
debug1: SSH2_MSG_KEXINIT sent
<<<
14131 1458466821.40484: stderr chunk (state=3):
>>>debug1: SSH2_MSG_KEXINIT received
debug2: kex_parse_kexinit: ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
debug2: kex_parse_kexinit: 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-rsa-cert-v01@openssh.com,ssh-dss-cert-v01@openssh.com,ssh-rsa-cert-v00@openssh.com,ssh-dss-cert-v00@openssh.com,ssh-rsa,ssh-dss
debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@lysator.liu.se
debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@lysator.liu.se
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: zlib@openssh.com,zlib,none
debug2: kex_parse_kexinit: zlib@openssh.com,zlib,none
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit: first_kex_follows 0
debug2: kex_parse_kexinit: reserved 0
debug2: kex_parse_kexinit: curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha1
debug2: kex_parse_kexinit: ssh-rsa,ssh-dss,ecdsa-sha2-nistp256,ssh-ed25519
debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,chacha20-poly1305@openssh.com
debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,chacha20-poly1305@openssh.com
debug2: kex_parse_kexinit: 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
debug2: kex_parse_kexinit: 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
debug2: kex_parse_kexinit: none,zlib@openssh.com
debug2: kex_parse_kexinit: none,zlib@openssh.com
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit: first_kex_follows 0
debug2: kex_parse_kexinit: reserved 0
debug2: mac_setup: found hmac-sha1
debug1: kex: server->client aes128-ctr hmac-sha1 zlib@openssh.com
debug2: mac_setup: found hmac-sha1
debug1: kex: client->server aes128-ctr hmac-sha1 zlib@openssh.com
<<<
14131 1458466821.40717: stderr chunk (state=3):
>>>debug1: sending SSH2_MSG_KEX_ECDH_INIT
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
<<<
14131 1458466821.45577: stderr chunk (state=3):
>>>debug1: Server host key: ECDSA 65:cf:64:76:7d:a2:4d:e5:51:3b:92:56:eb:00:b7:63
debug3: load_hostkeys: loading entries for host "vs5vb" from file "/home/oliver/.ssh/known_hosts"
<<<
14131 1458466821.45873: stderr chunk (state=3):
>>>debug3: load_hostkeys: found key type ECDSA in file /home/oliver/.ssh/known_hosts:82
debug3: load_hostkeys: loaded 1 keys
<<<
14131 1458466821.45887: stderr chunk (state=3):
>>>debug3: load_hostkeys: loading entries for host "192.168.0.27" from file "/home/oliver/.ssh/known_hosts"
<<<
14131 1458466821.46175: stderr chunk (state=3):
>>>debug3: load_hostkeys: found key type ECDSA in file /home/oliver/.ssh/known_hosts:83
debug3: load_hostkeys: loaded 1 keys
debug1: Host 'vs5vb' is known and matches the ECDSA host key.
debug1: Found key in /home/oliver/.ssh/known_hosts:82
<<<
14131 1458466821.46931: stderr chunk (state=3):
>>>debug1: ssh_ecdsa_verify: signature correct
debug2: kex_derive_keys
debug2: set_newkeys: mode 1
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
<<<
14131 1458466821.46962: stderr chunk (state=3):
>>>debug2: set_newkeys: mode 0
debug1: SSH2_MSG_NEWKEYS received
debug1: SSH2_MSG_SERVICE_REQUEST sent
<<<
14131 1458466821.51156: stderr chunk (state=3):
>>>debug2: service_accept: ssh-userauth
<<<
14131 1458466821.51187: stderr chunk (state=3):
>>>debug1: SSH2_MSG_SERVICE_ACCEPT received
<<<
14131 1458466821.51868: stderr chunk (state=3):
>>>debug2: key: /home/oliver/.ssh/id_rsa (0xb81e4c48)
debug2: key: /home/oliver/.ssh/id_dsa ((nil))
debug2: key: /home/oliver/.ssh/id_ecdsa ((nil))
<<<
14131 1458466821.53298: stderr chunk (state=3):
>>>debug1: Authentications that can continue: publickey,password
<<<
14131 1458466821.53355: stderr chunk (state=3):
>>>debug3: start over, passed a different list publickey,password
<<<
14131 1458466821.53384: stderr chunk (state=3):
>>>debug3: preferred gssapi-with-mic,gssapi-keyex,hostbased,publickey
<<<
14131 1458466821.53412: stderr chunk (state=3):
>>>debug3: authmethod_lookup publickey
<<<
14131 1458466821.53438: stderr chunk (state=3):
>>>debug3: remaining preferred: ,gssapi-keyex,hostbased,publickey
<<<
14131 1458466821.53462: stderr chunk (state=3):
>>>debug3: authmethod_is_enabled publickey
<<<
14131 1458466821.53487: stderr chunk (state=3):
>>>debug1: Next authentication method: publickey
<<<
14131 1458466821.53512: stderr chunk (state=3):
>>>debug1: Offering RSA public key: /home/oliver/.ssh/id_rsa
<<<
14131 1458466821.53544: stderr chunk (state=3):
>>>debug3: send_pubkey_test
<<<
14131 1458466821.53564: stderr chunk (state=3):
>>>debug2: we sent a publickey packet, wait for reply
<<<
14131 1458466821.53790: stderr chunk (state=3):
>>>debug1: Server accepts key: pkalg ssh-rsa blen 279
<<<
14131 1458466821.53805: stderr chunk (state=3):
>>>debug2: input_userauth_pk_ok: fp d0:a2:92:1a:ef:2a:2c:79:80:40:a8:f5:8a:40:14:20
<<<
14131 1458466821.53814: stderr chunk (state=3):
>>>debug3: sign_and_send_pubkey: RSA d0:a2:92:1a:ef:2a:2c:79:80:40:a8:f5:8a:40:14:20
<<<
14131 1458466821.56135: stderr chunk (state=3):
>>>debug1: Enabling compression at level 6.
<<<
14131 1458466821.56157: stderr chunk (state=3):
>>>debug1: Authentication succeeded (publickey).
<<<
14131 1458466821.56163: stderr chunk (state=3):
>>>Authenticated to vs5vb ([192.168.0.27]:22).
debug1: setting up multiplex master socket
<<<
14131 1458466821.56168: stderr chunk (state=3):
>>>debug3: muxserver_listen: temporary control path /home/oliver/.ansible/cp/ansible-ssh-vs5vb-22-oliver.Jag55qkaRlFDxOWV
<<<
14131 1458466821.56185: stderr chunk (state=3):
>>>debug2: fd 4 setting O_NONBLOCK
<<<
14131 1458466821.56191: stderr chunk (state=3):
>>>debug3: fd 4 is O_NONBLOCK
debug3: fd 4 is O_NONBLOCK
debug1: channel 0: new [/home/oliver/.ansible/cp/ansible-ssh-vs5vb-22-oliver]
debug3: muxserver_listen: mux listener channel 0 fd 4
<<<
14131 1458466821.56195: stderr chunk (state=3):
>>>debug1: control_persist_detach: backgrounding master process
<<<
14131 1458466821.56246: stderr chunk (state=3):
>>>debug2: control_persist_detach: background process is 14135
<<<
14131 1458466821.56263: stderr chunk (state=3):
>>>debug2: fd 4 setting O_NONBLOCK
debug1: forking to background
<<<
14131 1458466821.56287: stderr chunk (state=3):
>>>debug1: Entering interactive session.
<<<
14131 1458466821.56296: stderr chunk (state=3):
>>>debug2: set_control_persist_exit_time: schedule exit in 60 seconds
debug1: multiplexing control connection
<<<
14131 1458466821.56303: stderr chunk (state=3):
>>>debug2: fd 5 setting O_NONBLOCK
debug3: fd 5 is O_NONBLOCK
debug1: channel 1: new [mux-control]
debug3: channel_post_mux_listener: new mux channel 1 fd 5
<<<
14131 1458466821.56308: stderr chunk (state=3):
>>>debug3: mux_master_read_cb: channel 1: hello sent
debug2: set_control_persist_exit_time: cancel scheduled exit
<<<
14131 1458466821.56312: stderr chunk (state=3):
>>>debug3: mux_master_read_cb: channel 1 packet type 0x00000001 len 4
debug2: process_mux_master_hello: channel 1 slave version 4
<<<
14131 1458466821.56319: stderr chunk (state=3):
>>>debug2: mux_client_hello_exchange: master version 4
debug3: mux_client_request_forwards: requesting forwardings: 0 local, 0 remote
debug3: mux_client_request_session: entering
debug3: mux_client_request_alive: entering
<<<
14131 1458466821.56342: stderr chunk (state=3):
>>>debug3: mux_master_read_cb: channel 1 packet type 0x10000004 len 4
debug2: process_mux_alive_check: channel 1: alive check
<<<
14131 1458466821.56386: stderr chunk (state=3):
>>>debug3: mux_client_request_alive: done pid = 14136
<<<
14131 1458466821.56440: stderr chunk (state=3):
>>>debug3: mux_master_read_cb: channel 1 packet type 0x10000002 len 239
debug2: process_mux_new_session: channel 1: request tty 1, X 0, agent 0, subsys 0, term "xterm", cmd "/bin/sh -c '( umask 22 && mkdir -p "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" && echo "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" )'", env 1
debug3: mm_receive_fd: recvmsg: Resource temporarily unavailable
<<<
14131 1458466821.56477: stderr chunk (state=3):
>>>debug3: mm_receive_fd: recvmsg: Resource temporarily unavailable
<<<
14131 1458466821.56506: stderr chunk (state=3):
>>>debug3: mm_receive_fd: recvmsg: Resource temporarily unavailable
<<<
14131 1458466821.56599: stderr chunk (state=3):
>>>debug3: process_mux_new_session: got fds stdin 6, stdout 7, stderr 8
debug2: fd 7 setting O_NONBLOCK
debug2: fd 8 setting O_NONBLOCK
debug1: channel 2: new [client-session]
debug2: process_mux_new_session: channel_new: 2 linked to control channel 1
debug2: channel 2: send open
<<<
14131 1458466821.56641: stderr chunk (state=3):
>>>debug3: mux_client_request_session: session request sent
<<<
14131 1458466821.57654: stderr chunk (state=3):
>>>debug2: callback start
debug2: client_session2_setup: id 2
debug2: fd 3 setting TCP_NODELAY
debug2: channel 2: request pty-req confirm 1
<<<
14131 1458466821.57669: stderr chunk (state=3):
>>>debug1: Sending environment.
debug1: Sending env LANG =
debug2: channel 2: request env confirm 0
debug1: Sending command: /bin/sh -c '( umask 22 && mkdir -p "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" && echo "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" )'
debug2: channel 2: request exec confirm 1
debug3: mux_session_confirm: sending success reply
<<<
14131 1458466821.57675: stderr chunk (state=3):
>>>debug2: callback done
debug2: channel 2: open confirm rwindow 0 rmax 32768
debug1: mux_client_request_session: master session id: 2
<<<
14131 1458466821.57850: stderr chunk (state=3):
>>>debug2: channel_input_status_confirm: type 99 id 2
debug2: PTY allocation request accepted on channel 2
debug2: channel 2: rcvd adjust 2097152
debug2: channel_input_status_confirm: type 99 id 2
debug2: exec request accepted on channel 2
<<<
14131 1458466821.58508: stderr chunk (state=3):
>>>debug1: client_input_channel_req: channel 2 rtype exit-status reply 0
debug3: mux_exit_message: channel 2: exit message, evitval 0
debug1: client_input_channel_req: channel 2 rtype eow@openssh.com reply 0
debug2: channel 2: rcvd eow
debug2: channel 2: close_read
debug2: channel 2: input open -> closed
debug2: channel 2: rcvd eof
debug2: channel 2: output open -> drain
debug2: channel 2: obuf empty
debug2: channel 2: close_write
debug2: channel 2: output drain -> closed
debug2: channel 2: rcvd close
debug3: channel 2: will not send data after close
debug2: channel 2: send close
debug2: channel 2: is dead
debug2: channel 2: gc: notify user
debug3: mux_master_session_cleanup_cb: entering for channel 2
debug2: channel 1: rcvd close
debug2: channel 1: output open -> drain
debug2: channel 1: close_read
debug2: channel 1: input open -> closed
<<<
14131 1458466821.58536: stdout chunk (state=3):
>>>/home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884
<<<
14131 1458466821.58548: stderr chunk (state=3):
>>>debug2: channel 2: gc: user detached
debug2: channel 2: is dead
debug2: channel 2: garbage collecting
debug1: channel 2: free: client-session, nchannels 3
debug3: channel 2: status: The following connections are open:
#2 client-session (t4 r0 i3/0 o3/0 fd -1/-1 cc -1)
debug2: channel 1: obuf empty
debug2: channel 1: close_write
debug2: channel 1: output drain -> closed
debug2: channel 1: is dead (local)
debug2: channel 1: gc: notify user
debug3: mux_master_control_cleanup_cb: entering for channel 1
debug2: channel 1: gc: user detached
debug2: channel 1: is dead (local)
debug2: channel 1: garbage collecting
debug1: channel 1: free: mux-control, nchannels 2
debug3: channel 1: status: The following connections are open:
debug2: set_control_persist_exit_time: schedule exit in 60 seconds
debug3: mux_client_read_packet: read header failed: Broken pipe
debug2: Received exit status from master 0
Shared connection to vs5vb closed.
<<<
14131 1458466821.58622: stdout chunk (state=3):
>>><<<
14131 1458466821.58671: _low_level_execute_command() done: rc=0, stdout=/home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884
, stderr=OpenSSH_5.9p1 Debian-5ubuntu1.8, OpenSSL 1.0.1 14 Mar 2012
debug1: Reading configuration data /home/oliver/.ssh/config
debug1: Reading configuration data /etc/ssh/ssh_config
debug1: /etc/ssh/ssh_config line 19: Applying options for *
debug1: auto-mux: Trying existing master
debug1: Control socket "/home/oliver/.ansible/cp/ansible-ssh-vs5vb-22-oliver" does not exist
debug2: ssh_connect: needpriv 0
debug1: Connecting to vs5vb [192.168.0.27] port 22.
debug2: fd 3 setting O_NONBLOCK
debug1: fd 3 clearing O_NONBLOCK
debug1: Connection established.
debug3: timeout: 9997 ms remain after connect
debug3: Incorrect RSA1 identifier
debug3: Could not load "/home/oliver/.ssh/id_rsa" as a RSA1 public key
debug1: identity file /home/oliver/.ssh/id_rsa type 1
debug1: Checking blacklist file /usr/share/ssh/blacklist.RSA-2048
debug1: Checking blacklist file /etc/ssh/blacklist.RSA-2048
debug1: identity file /home/oliver/.ssh/id_rsa-cert type -1
debug1: identity file /home/oliver/.ssh/id_dsa type -1
debug1: identity file /home/oliver/.ssh/id_dsa-cert type -1
debug1: identity file /home/oliver/.ssh/id_ecdsa type -1
debug1: identity file /home/oliver/.ssh/id_ecdsa-cert type -1
debug1: Remote protocol version 2.0, remote software version OpenSSH_6.7p1 Debian-5+deb8u1
debug1: match: OpenSSH_6.7p1 Debian-5+deb8u1 pat OpenSSH*
debug1: Enabling compatibility mode for protocol 2.0
debug1: Local version string SSH-2.0-OpenSSH_5.9p1 Debian-5ubuntu1.8
debug2: fd 3 setting O_NONBLOCK
debug3: load_hostkeys: loading entries for host "vs5vb" from file "/home/oliver/.ssh/known_hosts"
debug3: load_hostkeys: found key type ECDSA in file /home/oliver/.ssh/known_hosts:82
debug3: load_hostkeys: loaded 1 keys
debug3: 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
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug2: kex_parse_kexinit: ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
debug2: kex_parse_kexinit: 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-rsa-cert-v01@openssh.com,ssh-dss-cert-v01@openssh.com,ssh-rsa-cert-v00@openssh.com,ssh-dss-cert-v00@openssh.com,ssh-rsa,ssh-dss
debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@lysator.liu.se
debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@lysator.liu.se
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: zlib@openssh.com,zlib,none
debug2: kex_parse_kexinit: zlib@openssh.com,zlib,none
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit: first_kex_follows 0
debug2: kex_parse_kexinit: reserved 0
debug2: kex_parse_kexinit: curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha1
debug2: kex_parse_kexinit: ssh-rsa,ssh-dss,ecdsa-sha2-nistp256,ssh-ed25519
debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,chacha20-poly1305@openssh.com
debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,chacha20-poly1305@openssh.com
debug2: kex_parse_kexinit: 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
debug2: kex_parse_kexinit: 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
debug2: kex_parse_kexinit: none,zlib@openssh.com
debug2: kex_parse_kexinit: none,zlib@openssh.com
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit: first_kex_follows 0
debug2: kex_parse_kexinit: reserved 0
debug2: mac_setup: found hmac-sha1
debug1: kex: server->client aes128-ctr hmac-sha1 zlib@openssh.com
debug2: mac_setup: found hmac-sha1
debug1: kex: client->server aes128-ctr hmac-sha1 zlib@openssh.com
debug1: sending SSH2_MSG_KEX_ECDH_INIT
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ECDSA 65:cf:64:76:7d:a2:4d:e5:51:3b:92:56:eb:00:b7:63
debug3: load_hostkeys: loading entries for host "vs5vb" from file "/home/oliver/.ssh/known_hosts"
debug3: load_hostkeys: found key type ECDSA in file /home/oliver/.ssh/known_hosts:82
debug3: load_hostkeys: loaded 1 keys
debug3: load_hostkeys: loading entries for host "192.168.0.27" from file "/home/oliver/.ssh/known_hosts"
debug3: load_hostkeys: found key type ECDSA in file /home/oliver/.ssh/known_hosts:83
debug3: load_hostkeys: loaded 1 keys
debug1: Host 'vs5vb' is known and matches the ECDSA host key.
debug1: Found key in /home/oliver/.ssh/known_hosts:82
debug1: ssh_ecdsa_verify: signature correct
debug2: kex_derive_keys
debug2: set_newkeys: mode 1
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug2: set_newkeys: mode 0
debug1: SSH2_MSG_NEWKEYS received
debug1: SSH2_MSG_SERVICE_REQUEST sent
debug2: service_accept: ssh-userauth
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug2: key: /home/oliver/.ssh/id_rsa (0xb81e4c48)
debug2: key: /home/oliver/.ssh/id_dsa ((nil))
debug2: key: /home/oliver/.ssh/id_ecdsa ((nil))
debug1: Authentications that can continue: publickey,password
debug3: start over, passed a different list publickey,password
debug3: preferred gssapi-with-mic,gssapi-keyex,hostbased,publickey
debug3: authmethod_lookup publickey
debug3: remaining preferred: ,gssapi-keyex,hostbased,publickey
debug3: authmethod_is_enabled publickey
debug1: Next authentication method: publickey
debug1: Offering RSA public key: /home/oliver/.ssh/id_rsa
debug3: send_pubkey_test
debug2: we sent a publickey packet, wait for reply
debug1: Server accepts key: pkalg ssh-rsa blen 279
debug2: input_userauth_pk_ok: fp d0:a2:92:1a:ef:2a:2c:79:80:40:a8:f5:8a:40:14:20
debug3: sign_and_send_pubkey: RSA d0:a2:92:1a:ef:2a:2c:79:80:40:a8:f5:8a:40:14:20
debug1: Enabling compression at level 6.
debug1: Authentication succeeded (publickey).
Authenticated to vs5vb ([192.168.0.27]:22).
debug1: setting up multiplex master socket
debug3: muxserver_listen: temporary control path /home/oliver/.ansible/cp/ansible-ssh-vs5vb-22-oliver.Jag55qkaRlFDxOWV
debug2: fd 4 setting O_NONBLOCK
debug3: fd 4 is O_NONBLOCK
debug3: fd 4 is O_NONBLOCK
debug1: channel 0: new [/home/oliver/.ansible/cp/ansible-ssh-vs5vb-22-oliver]
debug3: muxserver_listen: mux listener channel 0 fd 4
debug1: control_persist_detach: backgrounding master process
debug2: control_persist_detach: background process is 14135
debug2: fd 4 setting O_NONBLOCK
debug1: forking to background
debug1: Entering interactive session.
debug2: set_control_persist_exit_time: schedule exit in 60 seconds
debug1: multiplexing control connection
debug2: fd 5 setting O_NONBLOCK
debug3: fd 5 is O_NONBLOCK
debug1: channel 1: new [mux-control]
debug3: channel_post_mux_listener: new mux channel 1 fd 5
debug3: mux_master_read_cb: channel 1: hello sent
debug2: set_control_persist_exit_time: cancel scheduled exit
debug3: mux_master_read_cb: channel 1 packet type 0x00000001 len 4
debug2: process_mux_master_hello: channel 1 slave version 4
debug2: mux_client_hello_exchange: master version 4
debug3: mux_client_request_forwards: requesting forwardings: 0 local, 0 remote
debug3: mux_client_request_session: entering
debug3: mux_client_request_alive: entering
debug3: mux_master_read_cb: channel 1 packet type 0x10000004 len 4
debug2: process_mux_alive_check: channel 1: alive check
debug3: mux_client_request_alive: done pid = 14136
debug3: mux_master_read_cb: channel 1 packet type 0x10000002 len 239
debug2: process_mux_new_session: channel 1: request tty 1, X 0, agent 0, subsys 0, term "xterm", cmd "/bin/sh -c '( umask 22 && mkdir -p "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" && echo "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" )'", env 1
debug3: mm_receive_fd: recvmsg: Resource temporarily unavailable
debug3: mm_receive_fd: recvmsg: Resource temporarily unavailable
debug3: mm_receive_fd: recvmsg: Resource temporarily unavailable
debug3: process_mux_new_session: got fds stdin 6, stdout 7, stderr 8
debug2: fd 7 setting O_NONBLOCK
debug2: fd 8 setting O_NONBLOCK
debug1: channel 2: new [client-session]
debug2: process_mux_new_session: channel_new: 2 linked to control channel 1
debug2: channel 2: send open
debug3: mux_client_request_session: session request sent
debug2: callback start
debug2: client_session2_setup: id 2
debug2: fd 3 setting TCP_NODELAY
debug2: channel 2: request pty-req confirm 1
debug1: Sending environment.
debug1: Sending env LANG =
debug2: channel 2: request env confirm 0
debug1: Sending command: /bin/sh -c '( umask 22 && mkdir -p "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" && echo "` echo $HOME/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884 `" )'
debug2: channel 2: request exec confirm 1
debug3: mux_session_confirm: sending success reply
debug2: callback done
debug2: channel 2: open confirm rwindow 0 rmax 32768
debug1: mux_client_request_session: master session id: 2
debug2: channel_input_status_confirm: type 99 id 2
debug2: PTY allocation request accepted on channel 2
debug2: channel 2: rcvd adjust 2097152
debug2: channel_input_status_confirm: type 99 id 2
debug2: exec request accepted on channel 2
debug1: client_input_channel_req: channel 2 rtype exit-status reply 0
debug3: mux_exit_message: channel 2: exit message, evitval 0
debug1: client_input_channel_req: channel 2 rtype eow@openssh.com reply 0
debug2: channel 2: rcvd eow
debug2: channel 2: close_read
debug2: channel 2: input open -> closed
debug2: channel 2: rcvd eof
debug2: channel 2: output open -> drain
debug2: channel 2: obuf empty
debug2: channel 2: close_write
debug2: channel 2: output drain -> closed
debug2: channel 2: rcvd close
debug3: channel 2: will not send data after close
debug2: channel 2: send close
debug2: channel 2: is dead
debug2: channel 2: gc: notify user
debug3: mux_master_session_cleanup_cb: entering for channel 2
debug2: channel 1: rcvd close
debug2: channel 1: output open -> drain
debug2: channel 1: close_read
debug2: channel 1: input open -> closed
debug2: channel 2: gc: user detached
debug2: channel 2: is dead
debug2: channel 2: garbage collecting
debug1: channel 2: free: client-session, nchannels 3
debug3: channel 2: status: The following connections are open:
#2 client-session (t4 r0 i3/0 o3/0 fd -1/-1 cc -1)
debug2: channel 1: obuf empty
debug2: channel 1: close_write
debug2: channel 1: output drain -> closed
debug2: channel 1: is dead (local)
debug2: channel 1: gc: notify user
debug3: mux_master_control_cleanup_cb: entering for channel 1
debug2: channel 1: gc: user detached
debug2: channel 1: is dead (local)
debug2: channel 1: garbage collecting
debug1: channel 1: free: mux-control, nchannels 2
debug3: channel 1: status: The following connections are open:
debug2: set_control_persist_exit_time: schedule exit in 60 seconds
debug3: mux_client_read_packet: read header failed: Broken pipe
debug2: Received exit status from master 0
Shared connection to vs5vb closed.
14131 1458466821.61048: transferring module to remote
<vs5vb> PUT /tmp/tmp4hRRXx TO /home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884/ping
<vs5vb> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=60s)
<vs5vb> SSH: ansible_password/ansible_ssh_pass not set: (-o)(KbdInteractiveAuthentication=no)(-o)(PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey)(-o)(PasswordAuthentication=no)
<vs5vb> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10)
<vs5vb> SSH: PlayContext set ssh_common_args: ()
<vs5vb> SSH: PlayContext set sftp_extra_args: ()
<vs5vb> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/home/oliver/.ansible/cp/ansible-ssh-%h-%p-%r)
<vs5vb> SSH: EXEC sftp -b - -C -vvv -o ControlMaster=auto -o ControlPersist=60s -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o ConnectTimeout=10 -o ControlPath=/home/oliver/.ansible/cp/ansible-ssh-%h-%p-%r '[vs5vb]'
14131 1458466821.61950: Sending initial data
14131 1458466821.61983: Sent initial data (92 bytes)
14131 1458466821.62727: stderr chunk (state=3):
>>>OpenSSH_5.9p1 Debian-5ubuntu1.8, OpenSSL 1.0.1 14 Mar 2012
<<<
14131 1458466821.63499: stderr chunk (state=3):
>>>debug1: Reading configuration data /home/oliver/.ssh/config
<<<
14131 1458466821.63543: stderr chunk (state=3):
>>>debug1: Reading configuration data /etc/ssh/ssh_config
debug1: /etc/ssh/ssh_config line 19: Applying options for *
<<<
14131 1458466821.63671: stderr chunk (state=3):
>>>debug1: auto-mux: Trying existing master
debug2: fd 3 setting O_NONBLOCK
<<<
14131 1458466821.63704: stderr chunk (state=3):
>>>debug2: mux_client_hello_exchange: master version 4
debug3: mux_client_request_forwards: requesting forwardings: 0 local, 0 remote
debug3: mux_client_request_session: entering
debug3: mux_client_request_alive: entering
debug3: mux_client_request_alive: done pid = 14136
debug3: mux_client_request_session: session request sent
<<<
14131 1458466821.63862: stderr chunk (state=3):
>>>debug1: mux_client_request_session: master session id: 2
<<<
14131 1458466821.64795: stderr chunk (state=3):
>>>debug2: Remote version: 3
debug2: Server supports extension "posix-rename@openssh.com" revision 1
debug2: Server supports extension "statvfs@openssh.com" revision 2
debug2: Server supports extension "fstatvfs@openssh.com" revision 2
debug2: Server supports extension "hardlink@openssh.com" revision 1
debug2: Unrecognised server extension "fsync@openssh.com"
<<<
14131 1458466821.64846: stderr chunk (state=3):
>>>debug3: Sent message fd 3 T:16 I:3108716944
<<<
14131 1458466821.64975: stderr chunk (state=3):
>>>debug3: SSH_FXP_REALPATH . -> /home/oliver
<<<
14131 1458466821.65223: stdout chunk (state=3):
>>>sftp> put /tmp/tmp4hRRXx /home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884/ping
Uploading /tmp/tmp4hRRXx to /home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884/ping
<<<
14131 1458466821.65253: stderr chunk (state=3):
>>>debug3: Looking up /tmp/tmp4hRRXx
debug3: Sent message fd 3 T:17 I:3108716945
debug3: Received stat reply T:101 I:3108716945
debug1: Couldn't stat remote file: No such file or directory
debug3: Sent message SSH2_FXP_OPEN I:3108716946 P:/home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884/ping
<<<
14131 1458466821.65352: stderr chunk (state=3):
>>>debug3: Sent message SSH2_FXP_WRITE I:3108716947 O:0 S:32768
<<<
14131 1458466821.66062: stderr chunk (state=3):
>>>debug3: SSH2_FXP_STATUS 0
debug3: In write loop, ack for 3108716947 32768 bytes at 0
debug3: Sent message SSH2_FXP_WRITE I:3108716948 O:32768 S:32768
debug3: Sent message SSH2_FXP_WRITE I:3108716949 O:65536 S:10145
<<<
14131 1458466821.66890: stderr chunk (state=3):
>>>debug3: SSH2_FXP_STATUS 0
debug3: In write loop, ack for 3108716948 32768 bytes at 32768
debug3: SSH2_FXP_STATUS 0
debug3: In write loop, ack for 3108716949 10145 bytes at 65536
debug3: Sent message SSH2_FXP_CLOSE I:3108716947
<<<
14131 1458466821.67043: stderr chunk (state=3):
>>>debug3: SSH2_FXP_STATUS 0
<<<
14131 1458466821.67346: stderr chunk (state=3):
>>>debug3: mux_client_read_packet: read header failed: Broken pipe
debug2: Received exit status from master 0
<<<
14131 1458466821.67427: stdout chunk (state=3):
>>><<<
14131 1458466821.67432: stderr chunk (state=3):
>>><<<
14131 1458466821.67448: done transferring module to remote
14131 1458466821.67474: _low_level_execute_command(): starting
14131 1458466821.67478: _low_level_execute_command(): using become for this command
14131 1458466821.67497: _low_level_execute_command(): executing: /bin/sh -c 'su root -c /bin/sh -c '"'"'echo BECOME-SUCCESS-ewdtwfwfrqtkgnxnvcrudqkoijywfyyw; /bin/sh -c '"'"'"'"'"'"'"'"'LANG='"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"''"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' LC_ALL='"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"''"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' LC_MESSAGES='"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"''"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' /usr/bin/python /home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884/ping; rm -rf "/home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884/" > /dev/null 2>&1'"'"'"'"'"'"'"'"''"'"''
<vs5vb> ESTABLISH SSH CONNECTION FOR USER: None
<vs5vb> SSH: ansible.cfg set ssh_args: (-o)(ControlMaster=auto)(-o)(ControlPersist=60s)
<vs5vb> SSH: ansible_password/ansible_ssh_pass not set: (-o)(KbdInteractiveAuthentication=no)(-o)(PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey)(-o)(PasswordAuthentication=no)
<vs5vb> SSH: ANSIBLE_TIMEOUT/timeout set: (-o)(ConnectTimeout=10)
<vs5vb> SSH: PlayContext set ssh_common_args: ()
<vs5vb> SSH: PlayContext set ssh_extra_args: ()
<vs5vb> SSH: found only ControlPersist; added ControlPath: (-o)(ControlPath=/home/oliver/.ansible/cp/ansible-ssh-%h-%p-%r)
<vs5vb> SSH: EXEC ssh -C -vvv -o ControlMaster=auto -o ControlPersist=60s -o KbdInteractiveAuthentication=no -o PreferredAuthentications=gssapi-with-mic,gssapi-keyex,hostbased,publickey -o PasswordAuthentication=no -o ConnectTimeout=10 -o ControlPath=/home/oliver/.ansible/cp/ansible-ssh-%h-%p-%r -tt vs5vb '/bin/sh -c '"'"'su root -c /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-ewdtwfwfrqtkgnxnvcrudqkoijywfyyw; /bin/sh -c '"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'LANG='"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"''"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' LC_ALL='"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"''"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' LC_MESSAGES='"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"''"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"' /usr/bin/python /home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884/ping; rm -rf "/home/oliver/.ansible/tmp/ansible-tmp-1458466821.36-174514198052884/" > /dev/null 2>&1'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"'"''"'"'"'"'"'"'"'"''"'"''
14131 1458466821.68406: Initial state: awaiting_escalation: BECOME-SUCCESS-ewdtwfwfrqtkgnxnvcrudqkoijywfyyw
14131 1458466821.68561: stderr chunk (state=1):
>>>OpenSSH_5.9p1 Debian-5ubuntu1.8, OpenSSL 1.0.1 14 Mar 2012
<<<
14131 1458466821.69196: stderr chunk (state=1):
>>>debug1: Reading configuration data /home/oliver/.ssh/config
<<<
14131 1458466821.69318: stderr chunk (state=1):
>>>debug1: Reading configuration data /etc/ssh/ssh_config
debug1: /etc/ssh/ssh_config line 19: Applying options for *
debug1: auto-mux: Trying existing master
debug2: fd 3 setting O_NONBLOCK
debug2: mux_client_hello_exchange: master version 4
debug3: mux_client_request_forwards: requesting forwardings: 0 local, 0 remote
debug3: mux_client_request_session: entering
debug3: mux_client_request_alive: entering
debug3: mux_client_request_alive: done pid = 14136
debug3: mux_client_request_session: session request sent
<<<
14131 1458466821.69590: stderr chunk (state=1):
>>>debug1: mux_client_request_session: master session id: 2
<<<
14131 1458466821.71050: stdout chunk (state=1):
>>>Password: <<<
14131 1458466833.72015: done running TaskExecutor() for vs5vb/TASK: ping
14131 1458466833.72028: sending task result
14131 1458466833.72259: done sending task result
14131 1458466833.72524: WORKER PROCESS EXITING
14130 1458466833.72552: worker 0 has data to read
14130 1458466833.72882: got a result from worker 0: <ansible.executor.task_result.TaskResult object at 0x95159ac>
14130 1458466833.72909: sending result: [u'host_task_failed', u'<ansible.executor.task_result.TaskResult object at 0x95159ac>']
14130 1458466833.73017: done sending result
14117 1458466833.73316: got result from result worker: [u'host_task_failed', u'<ansible.executor.task_result.TaskResult object at 0x952a5cc>']
14117 1458466833.73343: marking vs5vb as failed
vs5vb | FAILED! => {
"failed": true,
"msg": "Timeout (12s) waiting for privilege escalation prompt: "
}
14117 1458466833.73459: no more pending results, returning what we have
14117 1458466833.73479: results queue empty
14117 1458466833.73489: checking for any_errors_fatal
14117 1458466833.73501: done checking for any_errors_fatal
14117 1458466833.73512: getting the remaining hosts for this loop
14117 1458466833.73533: done getting the remaining hosts for this loop
14117 1458466833.73604: building list of next tasks for hosts
14117 1458466833.73617: done building task lists
14117 1458466833.73628: counting tasks in each state of execution
14117 1458466833.73643: done counting tasks in each state of execution
14117 1458466833.73653: all hosts are done, so returning None's for all hosts
14117 1458466833.73665: done queuing things up, now waiting for results queue to drain
14117 1458466833.73675: waiting for pending results...
14117 1458466833.73685: no more pending results, returning what we have
14117 1458466833.73697: out of hosts to run on
14117 1458466833.73711: running handlers
14117 1458466833.73776: RUNNING CLEANUP
oliver:~/devel/vserver/ansible$
Raw
ansible.cfg
[defaults]
inventory = ./hosts
roles_path = ./roles
nocows = 1
[privilege_escalation]
become = True
become_method = su
Raw
hosts
[webserver]
vs5vb ansible_sudo_pass='pass'
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment